Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.09.2022 07: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 15042952 kB
MemAvailable 30605572 kB
Buffers 0 kB
Cached 16674228 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 07:37:16
DTS_END                         2022-09-26 07:37:16.7560
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/55260
TRN_ID                          212603
ATT_ID                          594
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: 8450.94 32351 1014113
customer order: creation 163.97 512 19676
customer order: refuse 14.23 489 1708
order to supplier: creation 45.09 1457 5411
order to supplier: removal 3.22 178484 386
invoice (draft): creation 44.41 3808 5329
invoice (draft): removal 46.02 1181 5522
invoice accept: apply 40.92 27703 4911
invoice accept: cancel 41.90 1492 5028
customer reserve: creation 3723.52 263 446822
customer reserve: removal 4042.03 110 485043
realization accept: apply 79.91 333 9589
realization accept: cancel 79.32 169 9518
payment from customer: creation 58.09 244 6971
payment from customer: removal 5.45 156 654
payment to supplier: creation 47.23 251 5667
payment to supplier: removal 3.32 169 398
service: total inventory turnovers 3.44 4908 413
service: total monetary turnovers 7.93 371 952
service: aggregate perf. data 0.92 16805 110
service: refresh index statistics 0.04 408109 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-09-2022 07:38:50 to 26-09-2022 07:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12049
TEST_TIME 2 8981
TEST_TIME 3 7810
TEST_TIME 4 8101
TEST_TIME 5 8148
TEST_TIME 6 7788
TEST_TIME 7 7687
TEST_TIME 8 7612
TEST_TIME 9 7837
TEST_TIME 10 7689
TEST_TIME 11 7795
TEST_TIME 12 7831
TEST_TIME 13 8164
TEST_TIME 14 8168
TEST_TIME 15 8138
TEST_TIME 16 8011
TEST_TIME 17 7973
TEST_TIME 18 8041
TEST_TIME 19 8269
TEST_TIME 20 8157
TEST_TIME 21 8236
TEST_TIME 22 8227
TEST_TIME 23 8245
TEST_TIME 24 8259
TEST_TIME 25 8370
TEST_TIME 26 8719
TEST_TIME 27 8849
TEST_TIME 28 8964
TEST_TIME 29 9286
TEST_TIME 30 9361
TEST_TIME 31 9493
TEST_TIME 32 9787
TEST_TIME 33 9860
TEST_TIME 34 9876
TEST_TIME 35 9922
TEST_TIME 36 10185
TEST_TIME 37 10348
TEST_TIME 38 10471
TEST_TIME 39 10473
TEST_TIME 40 10251
TEST_TIME 41 10302
TEST_TIME 42 10299
TEST_TIME 43 10291
TEST_TIME 44 10131
TEST_TIME 45 9969
TEST_TIME 46 10064
TEST_TIME 47 9994
TEST_TIME 48 9994
TEST_TIME 49 9839
TEST_TIME 50 9768
TEST_TIME 51 9617
TEST_TIME 52 9501
TEST_TIME 53 9467
TEST_TIME 54 9378
TEST_TIME 55 9282
TEST_TIME 56 9152
TEST_TIME 57 9136
TEST_TIME 58 9101
TEST_TIME 59 9101
TEST_TIME 60 9085
TEST_TIME 61 8994
TEST_TIME 62 8878
TEST_TIME 63 8899
TEST_TIME 64 8956
TEST_TIME 65 8865
TEST_TIME 66 9077
TEST_TIME 67 9025
TEST_TIME 68 9027
TEST_TIME 69 8993
TEST_TIME 70 8977
TEST_TIME 71 9035
TEST_TIME 72 9037
TEST_TIME 73 9038
TEST_TIME 74 9033
TEST_TIME 75 9018
TEST_TIME 76 9023
TEST_TIME 77 9052
TEST_TIME 78 9033
TEST_TIME 79 8941
TEST_TIME 80 8895
TEST_TIME 81 8872
TEST_TIME 82 8866
TEST_TIME 83 8845
TEST_TIME 84 8774
TEST_TIME 85 8734
TEST_TIME 86 8694
TEST_TIME 87 8655
TEST_TIME 88 8685
TEST_TIME 89 8634
TEST_TIME 90 8689
TEST_TIME 91 8684
TEST_TIME 92 8639
TEST_TIME 93 8623
TEST_TIME 94 8634
TEST_TIME 95 8681
TEST_TIME 96 8678
TEST_TIME 97 8674
TEST_TIME 98 8712
TEST_TIME 99 8755
TEST_TIME 100 8784
TEST_TIME 101 8790
TEST_TIME 102 8776
TEST_TIME 103 8742
TEST_TIME 104 8683
TEST_TIME 105 8684
TEST_TIME 106 8670
TEST_TIME 107 8624
TEST_TIME 108 8592
TEST_TIME 109 8560
TEST_TIME 110 8547
TEST_TIME 111 8493
TEST_TIME 112 8468
TEST_TIME 113 8493
TEST_TIME 114 8526
TEST_TIME 115 8506
TEST_TIME 116 8506
TEST_TIME 117 8494
TEST_TIME 118 8512
TEST_TIME 119 8522
TEST_TIME 120 8477

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-09-2022 07:38:53 to 26-09-2022 07:38:57.

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 1424745 1424745 0 0.00 0 27376 15 0 0
doc_list_biud 1424733 1424733 0 0.00 0 46853 45 0 0
sp_add_invoice_to_stock 5035 4911 124 2.46 36 181390 27704 0 124
sp_cancel_adding_invoice 5028 5028 0 0.00 6 92933 1492 0 0
sp_cancel_client_order 1708 1708 0 0.00 6 14952 490 0 0
sp_cancel_customer_reserve 485043 485043 0 0.00 1 26644 111 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 4 7413 156 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 14168 169 0 0
sp_cancel_supplier_invoice 5539 5522 17 0.30 3 46860 1182 0 17
sp_cancel_supplier_order 386 386 0 0.00 17 1670510 178484 0 0
sp_cancel_write_off 9518 9518 0 0.00 2 15398 169 0 0
sp_client_order 19676 19676 0 0.00 5 23298 513 0 0
sp_customer_reserve 446822 446822 0 0.00 0 49760 263 0 0
sp_fill_shopping_cart 30400 30400 0 0.00 1 30940 105 0 0
sp_fill_shopping_cart_clo_res 446796 446796 0 0.00 0 18234 22 0 0
sp_get_clo_for_invoice 4880 4880 0 0.00 0 36707 427 0 0
sp_kill_cost_storno 15598 15598 0 0.00 0 14144 84 0 0
sp_kill_qstorno_ret_qs2qd 985512 985512 0 0.00 0 46851 48 0 0
sp_kill_qty_storno 521673 521673 0 0.00 0 46853 113 0 0
sp_lock_selected_doc 20054 20054 0 0.00 0 8151 7 0 0
sp_make_cost_storno 27107 27107 0 0.00 0 27268 94 0 0
sp_multiply_rows_for_pdistr 27106 27106 0 0.00 0 15723 55 0 0
sp_multiply_rows_for_qdistr 19675 19675 0 0.00 0 13385 178 0 0
sp_pay_from_customer 6971 6971 0 0.00 5 34663 244 0 0
sp_pay_to_supplier 5667 5667 0 0.00 7 15601 251 0 0
sp_payment_common 12638 12638 0 0.00 2 33822 72 0 0
sp_qd_handle_on_cancel_clo 1708 1708 0 0.00 1 14942 397 0 0
sp_qd_handle_on_invoice_upd_sts 9907 9907 0 0.00 0 30051 632 0 0
sp_qd_handle_on_reserve_upd_sts 9589 9589 0 0.00 0 18050 73 0 0
sp_reserve_write_off 9589 9589 0 0.00 8 18072 334 0 0
sp_supplier_invoice 5329 5329 0 0.00 9 94779 3808 0 0
sp_supplier_order 5411 5411 0 0.00 8 40028 1458 0 0
srv_aggregate_perf_data 110 110 0 0.00 2084 111250 16806 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 702 135525 34558 0 0
srv_find_qd_qs_mism 966379 966379 0 0.00 0 12062 11 0 0
srv_make_invnt_saldo 413 413 0 0.00 54 54972 4909 0 0
srv_make_money_saldo 952 952 0 0.00 1 25777 372 0 0
srv_recalc_idx_stat 5 5 0 0.00 255607 620355 408109 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4915 4915 0 0.00 0 18196 1183 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 7050 564 0 0
t$perf-norm:sp_cancel_client_order 1708 1708 0 0.00 0 2446 36 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 1930 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 1149 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 222 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 7486 70 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 95100 8156 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 1077 23 0 0
t$perf-norm:sp_client_order 19676 19676 0 0.00 0 6041 43 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 3171 23 0 0
t$perf-norm:sp_pay_from_customer 6971 6971 0 0.00 0 7274 25 0 0
t$perf-norm:sp_pay_to_supplier 5667 5667 0 0.00 0 6572 24 0 0
t$perf-norm:sp_reserve_write_off 9589 9589 0 0.00 0 15627 34 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 16396 43 0 0
t$perf-norm:sp_supplier_order 5412 5412 0 0.00 0 7362 42 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 595 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 23 4 0 0
t$perf-norm:srv_make_invnt_saldo 413 413 0 0.00 0 4874 28 0 0
t$perf-norm:srv_make_money_saldo 952 952 0 0.00 0 18149 29 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 156 50 0 0
v_add_invoice_to_stock 9984 9736 248 2.48 0 5250 26 0 248
v_all_customers 36426 36426 0 0.00 0 70 4 0 0
v_all_suppliers 29653 29653 0 0.00 0 76 5 0 0
v_all_wares 39342 39342 0 0.00 0 106 5 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 440 16 0 0
v_cancel_client_order 3416 3416 0 0.00 0 11673 14 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 7390 18 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 1633 13 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 3932 16 0 0
v_cancel_supplier_invoice 824 790 34 4.12 0 6677 33 0 34
v_cancel_supplier_order 772 772 0 0.00 0 4605 21 0 0
v_cancel_write_off 1982 1982 0 0.00 0 2993 28 0 0
v_max_id_clo_ord 5410 5410 0 0.00 0 2930 6 0 0
v_max_id_clo_res 986 986 0 0.00 0 2594 20 0 0
v_max_id_ord_sup 5317 5317 0 0.00 0 3438 9 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 299 8 0 0
v_max_non_paid_realizn 3572 3572 0 0.00 0 8611 11 0 0
v_min_id_clo_ord 5410 5410 0 0.00 0 2913 10 0 0
v_min_id_clo_res 986 986 0 0.00 0 4253 15 0 0
v_min_id_ord_sup 5317 5317 0 0.00 0 6172 16 0 0
v_min_non_paid_invoice 5667 5667 0 0.00 0 15413 37 0 0
v_min_non_paid_realizn 6971 6971 0 0.00 0 33806 42 0 0
v_our_firm 9714 9714 0 0.00 0 156 5 0 0
v_reserve_write_off 19178 19178 0 0.00 0 15779 14 0 0
x_lock_depdocs_on_canc_invoice 10155 10155 0 0.00 0 11625 134 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 9159 445 0 0
x_make_qstorno_1000_1200 5411 5411 0 0.00 3 39983 1260 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 5 94717 3497 0 0
x_make_qstorno_2100_3300 446822 446822 0 0.00 0 47236 226 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-09-2022 07:38:58 to 26-09-2022 07:39:02.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:20 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:21 28041 8192000000 9731506176 10026975232 798054736 8.877 100 86 173 0 826473584 7237680 21181168
05:09:54 20745 8192000000 9769530160 10078027776 842335520 9.323 100 95 190 0 877186896 12228512 22622864
05:11:21 20037 8192000000 9802876080 10122174464 868724928 9.587 100 83 166 0 905937424 17923312 19289184
05:12:45 11733 8192000000 9858781600 10190712832 919457072 10.091 100 87 174 0 973532000 35272576 18802352
05:14:01 3219 8192000000 9933007552 10274205696 955298672 10.443 100 88 172 1 1043564592 70146672 18119248
05:15:07 1914 8192000000 9957467360 10307522560 979618352 10.680 100 92 177 3 1069642544 71951712 18072480
05:16:10 1240 8192000000 9997972336 10355666944 1007104048 10.947 100 94 184 1 1115436544 89684304 18648192
05:17:13 1647 8192000000 9992637824 10356244480 1022550512 11.097 100 87 170 1 1114555632 75276416 16728704
05:18:17 1089 8192000000 10019794272 10393083904 1047037168 11.332 100 96 183 1 1142785632 76460608 19287856
05:19:20 1358 8192000000 10057500560 10436296704 1064655808 11.501 100 94 177 3 1177841344 95349168 17836368
05:20:24 991 8192000000 10045218176 10425217024 1070456096 11.556 100 91 173 0 1168527952 81002480 17069376
05:21:27 1111 8192000000 10051136656 10433101824 1075811232 11.608 100 94 188 0 1170979392 75294304 19873856
05:22:31 1302 8192000000 10064317664 10453577728 1087480512 11.719 100 97 188 1 1192902928 85354144 20068272
05:23:34 1104 8192000000 10061901824 10447978496 1087358384 11.718 100 98 191 0 1183572576 76635632 19578560
05:24:37 1184 8192000000 10069309184 10456092672 1093066512 11.772 100 91 171 2 1185103840 74576000 17461328
05:25:39 1246 8192000000 10075192112 10465239040 1095748544 11.797 100 97 190 0 1191445024 76175648 19520832
05:26:43 1069 8192000000 10082483072 10470633472 1098418128 11.823 100 92 171 0 1202293312 84567648 19307536
05:27:45 974 8192000000 10051335616 10444488704 1098037424 11.819 100 87 165 2 1173658096 57944976 17675696
05:28:48 1046 8192000000 10054373248 10448732160 1098380560 11.822 100 96 186 1 1181131200 63442640 19308000
05:29:50 1305 8192000000 10050045952 10433552384 1090996432 11.752 100 97 192 1 1168771248 58486160 19288656
05:30:52 1311 8192000000 10041553424 10429812736 1087538832 11.719 100 79 144 4 1161053312 57421024 16093456
05:31:55 1198 8192000000 10013666592 10388484096 1034246080 11.209 97 88 172 1 1134670176 82712480 17711616
05:32:58 1256 8192000000 10011989856 10386534400 1043123584 11.295 100 97 181 0 1131095536 68341424 19630528
05:34:00 1320 8192000000 9987748528 10360889344 1039494816 11.260 100 91 169 0 1112642400 55880016 17267568
05:35:02 974 8192000000 10010509472 10386137088 1046328144 11.325 100 91 177 3 1131951072 66741376 18881552
05:36:05 4118 8192000000 9965893936 10341232640 999354304 10.872 97 69 124 2 1082821744 70760416 12707024
05:37:10 4908 8192000000 9989437568 10371788800 1038689440 11.252 100 69 130 1 1116547328 63659984 14197904
05:38:20 1714 8192000000 9983377776 10363183104 1033302464 11.200 99 70 134 0 1107740864 59783952 14654448
05:39:27 1908 8192000000 10037407856 10413060096 1062195312 11.477 100 90 180 1 1158873600 78317792 18360496
05:40:35 7811 8192000000 10043188736 10423279616 1067615312 11.529 100 72 139 1 1156492960 73818992 15058656
05:41:47 4499 8192000000 10091506080 10476306432 1086206384 11.707 100 75 136 1 1210783808 111098736 13478688
05:42:55 3614 8192000000 10103261440 10496585728 1102351056 11.860 100 73 144 0 1218119456 100488576 15279824
05:44:10 918 8192000000 10122034608 10511925248 1111279344 11.945 100 84 156 2 1230490416 102955056 16256016
05:45:16 4511 8192000000 10150717296 10552598528 1117049424 11.999 100 74 140 0 1261989104 130334736 14604944
05:46:25 1641 8192000000 10185677296 10584551424 1124388928 12.068 100 95 177 2 1304770048 159520160 20860960
05:47:33 2454 8192000000 10212940848 10608136192 1134635392 12.165 100 86 164 1 1319135184 165460720 19039072
05:48:40 2314 8192000000 10215435824 10619592704 1153280096 12.340 100 79 155 1 1327128304 156796016 17052192
05:49:52 1054 8192000000 10234160880 10657017856 1167664096 12.475 100 84 156 1 1350854608 165665616 17524896
05:50:56 856 8192000000 10265837312 10665385984 1173034864 12.525 100 95 183 0 1373990608 181952592 19003152
05:52:00 867 8192000000 10290076896 10688147456 1177837680 12.570 100 98 188 1 1396686112 198616800 20231632
05:53:04 1742 8192000000 10335706672 10734555136 1179914384 12.589 100 94 185 1 1433291264 232955952 20420928
05:54:10 2519 8192000000 10354114000 10753994752 1177208144 12.564 100 94 185 0 1453793568 255428784 21156640
05:55:15 2486 8192000000 10384323520 10809860096 1185833856 12.645 100 93 185 0 1491452688 283614096 22004736
05:56:22 1607 8192000000 10368948880 10791444480 1177998416 12.572 99 96 190 3 1480289856 278857424 23434016
05:57:29 1479 8192000000 10415483200 10815922176 1174947248 12.543 100 96 183 0 1513628384 318366624 20314512
05:58:35 695 8192000000 10445370352 10862346240 1185560352 12.642 100 98 190 0 1552403520 343956128 22887040
05:59:38 1295 8192000000 10448429792 10871717888 1169247520 12.490 100 94 179 0 1553759312 363908720 20603072
06:00:46 1791 8192000000 10474272864 10901028864 1175245472 12.546 100 93 182 1 1578630512 381097312 22287728
06:01:52 718 8192000000 10493615680 10927947776 1176713120 12.560 100 99 188 0 1596328288 396245536 23369632
06:02:57 1810 8192000000 10482955680 10952781824 1171680496 12.513 100 92 182 0 1581260832 386969296 22611040
06:04:02 788 8192000000 10505188816 10938896384 1188898464 12.673 100 98 195 0 1606522832 394159040 23465328
06:05:08 774 8192000000 10493680112 10918682624 1166591248 12.465 100 92 183 1 1596526720 408473472 21462000
06:06:12 9874 8192000000 10461538224 10944073728 1166165440 12.461 100 45 90 0 1561342672 383316272 11860960
06:07:27 16960 8192000000 10403032944 10839740416 1162506960 12.427 100 38 74 1 1502611552 330459008 9645584
06:09:03 13436 8192000000 10337485392 10846830592 1149559376 12.305 100 31 62 0 1457424400 299980032 7884992
06:10:38 33994 8192000000 10303996320 10757439488 1119889312 12.026 97 29 58 0 1375910256 248558464 7462480
06:12:15 3174 8192000000 10293617376 10748141568 1134371280 12.163 100 50 98 1 1397043344 251304160 11367904
06:13:26 18235 8192000000 10243287504 10757091328 1137789824 12.195 100 32 62 1 1343293680 198062896 7440960
06:14:52 16807 8192000000 10193377952 10669404160 1123097872 12.056 99 34 66 0 1302416864 171312576 8006416
06:16:10 39283 8192000000 10172474336 10630053888 1123133040 12.057 99 52 97 3 1272331024 139265136 9932848
06:18:41 56857 8192000000 10174356768 10649444352 1124846480 12.073 99 21 42 0 1279375584 150110272 4418832
06:20:39 75203 8192000000 10109374656 10557476864 1125686976 12.081 100 18 35 0 1207393408 78182352 3524080
06:22:58 73229 8192000000 10084882192 10548490240 1118972896 12.017 98 25 46 0 1153306880 28903488 5430496
06:25:18 118367 8192000000 10059912416 10503217152 1120675392 12.033 99 25 49 0 1208402624 82425472 5301760
06:28:22 58039 8192000000 10071382176 10511294464 1124613536 12.071 99 38 72 2 1164653040 32109568 7929936
06:30:25 43915 8192000000 10068366112 10515324928 1140134400 12.217 100 17 32 0 1192312880 48869376 3309104
06:32:11 52027 8192000000 10143393152 10599022592 1143219392 12.246 99 13 26 0 1231187680 85092080 2876208
06:34:49 72703 8192000000 10126599856 10593255424 1120333280 12.030 97 16 30 0 1215823664 92161520 3328864
06:37:05 81951 8192000000 10130287968 10574880768 1104986048 11.885 96 30 57 2 1173068464 62480304 5602112
06:40:03 76316 8192000000 10173639680 10696679424 1124353776 12.068 98 31 58 0 1286010320 155296080 6360464
06:42:27 76876 8192000000 10093151056 10658529280 1124343584 12.068 99 8 16 0 1250695792 124215520 2136688
06:44:46 51069 8192000000 10065955248 10499567616 1125117392 12.075 99 17 32 0 1171298928 42862656 3318880
06:46:40 18690 8192000000 10074261760 10508238848 1105242640 11.887 97 36 72 0 1146216416 34134304 6839472
06:48:06 86897 8192000000 10096845904 10537467904 1107021568 11.904 98 15 28 0 1176732992 66884608 2826816
06:50:43 40063 8192000000 10067227152 10508619776 1107985104 11.913 98 35 70 0 1160116736 45078912 7052720
06:52:24 47424 8192000000 10117572224 10569052160 1088632768 11.730 97 35 68 0 1162123408 66926816 6563824
06:54:22 135525 8192000000 10064960752 10528374784 1123989888 12.065 100 16 32 0 1191850000 64427968 3432144
06:57:42 74388 8192000000 10080169712 10518380544 1136298752 12.181 100 24 48 0 1203598416 62201776 5097888
06:59:59 41888 8192000000 10084668320 10538418176 1143572032 12.249 100 30 58 1 1183380560 33939264 5869264
07:01:43 51443 8192000000 10082493360 10540908544 1125236208 12.076 98 13 26 0 1183614864 55998384 2380272
07:03:36 79711 8192000000 10080792896 10539823104 1120940496 12.036 98 8 15 0 1170642112 48530144 1171472
07:05:57 17543 8192000000 10034388736 10469523456 1087038832 11.714 97 29 58 1 1123172592 29686704 6447056
07:07:20 16391 8192000000 10065644400 10509901824 1104302112 11.878 99 31 59 0 1169561744 59640992 5618640
07:08:47 47760 8192000000 10085248560 10517909504 1100332016 11.841 98 20 40 0 1143678848 39162176 4184656
07:10:40 23050 8192000000 10128948096 10560319488 1084803712 11.693 96 24 48 0 1186671904 96564928 5303264
07:12:10 59432 8192000000 10127746384 10566508544 1116123424 11.990 99 24 48 0 1213398768 91907168 5368176
07:14:11 54528 8192000000 10150205488 10595635200 1121106976 12.037 99 25 50 0 1239645408 113089760 5448672
07:16:09 62738 8192000000 10103405632 10569048064 1134839232 12.167 100 19 38 0 1225189040 86334432 4015376
07:18:15 31340 8192000000 10126156336 10550145024 1143275920 12.246 100 54 102 0 1206315680 52403312 10636448
07:19:51 115497 8192000000 10077879264 10511134720 1129758608 12.119 99 31 60 0 1182421584 45591680 7071296
07:23:38 79198 8192000000 10084417088 10574274560 1128596896 12.108 99 6 12 0 1173635520 43737536 1301088
07:25:57 60569 8192000000 10092485216 10553937920 1125054496 12.075 99 17 34 0 1193353856 64739152 3560208
07:27:58 38302 8192000000 10087687776 10546913280 1111561776 11.947 97 21 39 0 1165455760 49706832 4187152
07:29:38 91335 8192000000 10061816288 10535096320 1123109584 12.056 99 21 41 0 1222390976 94585008 4696384
07:32:09 28412 8192000000 10106833904 10543067136 1142100880 12.235 100 41 80 2 1204092368 53848432 8143056
07:33:38 30976 8192000000 10152289648 10602967040 1140759328 12.223 99 39 79 0 1263292720 114146496 8386896
07:35:10 33819 8192000000 10194279776 10636787712 1156485200 12.370 100 32 64 0 1289961632 126469776 7006656
07:36:48 46202 8192000000 9047789952 9325240320 174427968 2.084 15 6 12 0 258041264 82241024 1372272

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 07:39:04 to 26-09-2022 07:39:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-09-2022 07:39:21 to 26-09-2022 07:39:23.

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          212602
MON$OLDEST_ACTIVE               218768
MON$OLDEST_SNAPSHOT             218768
MON$NEXT_TRANSACTION            218768
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                       2098880
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             770
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 ---
--- end of diff output ---

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

26.09.22 07:39:24. 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 07:39:27 No severe PSQL-related problems occured
26.09.22 07:39:28. End of report.