Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.05.2022 04:37
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 16496008 kB
MemAvailable 31407488 kB
Buffers 20 kB
Cached 15121640 kB
SwapCached 2572 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-10 04:37:15
DTS_END                         2022-05-10 04:37:15.9190
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/58874
TRN_ID                          221139
ATT_ID                          595
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: 8885.11 20969 1066213
customer order: creation 174.29 488 20915
customer order: refuse 14.50 375 1740
order to supplier: creation 44.57 1403 5348
order to supplier: removal 3.29 167743 395
invoice (draft): creation 45.12 3271 5414
invoice (draft): removal 48.46 1067 5815
invoice accept: apply 42.07 27780 5048
invoice accept: cancel 43.88 1244 5265
customer reserve: creation 3937.48 265 472498
customer reserve: removal 4246.95 103 509634
realization accept: apply 81.18 253 9742
realization accept: cancel 76.84 172 9221
payment from customer: creation 57.25 155 6870
payment from customer: removal 5.78 109 694
payment to supplier: creation 47.06 168 5647
payment to supplier: removal 3.46 132 415
service: total inventory turnovers 4.36 3369 523
service: total monetary turnovers 7.53 297 904
service: aggregate perf. data 1.00 15540 120
service: refresh index statistics 0.04 195443 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-05-2022 04:37:57 to 10-05-2022 04:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9305
TEST_TIME 2 7604
TEST_TIME 3 7925
TEST_TIME 4 7084
TEST_TIME 5 6868
TEST_TIME 6 6456
TEST_TIME 7 6438
TEST_TIME 8 6239
TEST_TIME 9 6219
TEST_TIME 10 6081
TEST_TIME 11 6236
TEST_TIME 12 6523
TEST_TIME 13 6799
TEST_TIME 14 6870
TEST_TIME 15 7010
TEST_TIME 16 7099
TEST_TIME 17 7166
TEST_TIME 18 7583
TEST_TIME 19 7630
TEST_TIME 20 7737
TEST_TIME 21 8028
TEST_TIME 22 7963
TEST_TIME 23 7994
TEST_TIME 24 8200
TEST_TIME 25 8247
TEST_TIME 26 8569
TEST_TIME 27 8620
TEST_TIME 28 8859
TEST_TIME 29 9991
TEST_TIME 30 10294
TEST_TIME 31 10624
TEST_TIME 32 10827
TEST_TIME 33 10874
TEST_TIME 34 10820
TEST_TIME 35 10702
TEST_TIME 36 10584
TEST_TIME 37 10306
TEST_TIME 38 10134
TEST_TIME 39 10044
TEST_TIME 40 9923
TEST_TIME 41 9974
TEST_TIME 42 9900
TEST_TIME 43 9857
TEST_TIME 44 9819
TEST_TIME 45 9695
TEST_TIME 46 9657
TEST_TIME 47 9528
TEST_TIME 48 9605
TEST_TIME 49 9620
TEST_TIME 50 9717
TEST_TIME 51 9627
TEST_TIME 52 9624
TEST_TIME 53 9631
TEST_TIME 54 9737
TEST_TIME 55 9704
TEST_TIME 56 9672
TEST_TIME 57 9839
TEST_TIME 58 9820
TEST_TIME 59 9842
TEST_TIME 60 9689
TEST_TIME 61 9589
TEST_TIME 62 9524
TEST_TIME 63 9504
TEST_TIME 64 9418
TEST_TIME 65 9330
TEST_TIME 66 9344
TEST_TIME 67 9282
TEST_TIME 68 9239
TEST_TIME 69 9239
TEST_TIME 70 9230
TEST_TIME 71 9211
TEST_TIME 72 9172
TEST_TIME 73 9153
TEST_TIME 74 9224
TEST_TIME 75 9208
TEST_TIME 76 9159
TEST_TIME 77 9197
TEST_TIME 78 9199
TEST_TIME 79 9257
TEST_TIME 80 9204
TEST_TIME 81 9159
TEST_TIME 82 9139
TEST_TIME 83 9078
TEST_TIME 84 9065
TEST_TIME 85 9031
TEST_TIME 86 8972
TEST_TIME 87 8979
TEST_TIME 88 8987
TEST_TIME 89 8983
TEST_TIME 90 8972
TEST_TIME 91 8974
TEST_TIME 92 8930
TEST_TIME 93 9003
TEST_TIME 94 8982
TEST_TIME 95 9000
TEST_TIME 96 9062
TEST_TIME 97 9082
TEST_TIME 98 9097
TEST_TIME 99 9079
TEST_TIME 100 9137
TEST_TIME 101 9129
TEST_TIME 102 9073
TEST_TIME 103 9029
TEST_TIME 104 9027
TEST_TIME 105 9000
TEST_TIME 106 8949
TEST_TIME 107 8964
TEST_TIME 108 8918
TEST_TIME 109 8890
TEST_TIME 110 8862
TEST_TIME 111 8888
TEST_TIME 112 8860
TEST_TIME 113 8875
TEST_TIME 114 8862
TEST_TIME 115 8910
TEST_TIME 116 8953
TEST_TIME 117 8922
TEST_TIME 118 8905
TEST_TIME 119 8964
TEST_TIME 120 8912

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-05-2022 04:38:01 to 10-05-2022 04:38:04.

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 1499216 1499216 0 0.00 0 28244 13 0 0
doc_list_biud 1499201 1499201 0 0.00 0 43228 41 0 0
sp_add_invoice_to_stock 5145 5048 97 1.88 58 232610 27781 0 97
sp_cancel_adding_invoice 5265 5265 0 0.00 6 63374 1245 0 0
sp_cancel_client_order 1740 1740 0 0.00 8 7988 375 0 0
sp_cancel_customer_reserve 509634 509634 0 0.00 1 30142 104 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 4 8100 110 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 6810 133 0 0
sp_cancel_supplier_invoice 5817 5815 2 0.03 3 30403 1068 0 2
sp_cancel_supplier_order 395 395 0 0.00 11 1238113 167744 0 0
sp_cancel_write_off 9221 9221 0 0.00 2 29954 173 0 0
sp_client_order 20915 20915 0 0.00 6 28582 488 0 0
sp_customer_reserve 472498 472498 0 0.00 1 155922 266 0 0
sp_fill_shopping_cart 31665 31665 0 0.00 2 14337 81 0 0
sp_fill_shopping_cart_clo_res 472462 472462 0 0.00 0 16886 21 0 0
sp_get_clo_for_invoice 5003 5003 0 0.00 0 26235 316 0 0
sp_kill_cost_storno 15595 15595 0 0.00 0 9064 65 0 0
sp_kill_qstorno_ret_qs2qd 1034699 1034699 0 0.00 0 30386 46 0 0
sp_kill_qty_storno 546810 546810 0 0.00 0 43228 104 0 0
sp_lock_selected_doc 20472 20472 0 0.00 0 10350 9 0 0
sp_make_cost_storno 27261 27261 0 0.00 0 16548 70 0 0
sp_multiply_rows_for_pdistr 27258 27258 0 0.00 0 15892 34 0 0
sp_multiply_rows_for_qdistr 20915 20915 0 0.00 0 12075 219 0 0
sp_pay_from_customer 6870 6870 0 0.00 6 16562 155 0 0
sp_pay_to_supplier 5647 5647 0 0.00 7 8490 168 0 0
sp_payment_common 12515 12515 0 0.00 2 7694 39 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 7977 307 0 0
sp_qd_handle_on_invoice_upd_sts 10263 10263 0 0.00 0 43227 481 0 0
sp_qd_handle_on_reserve_upd_sts 9742 9742 0 0.00 0 16952 59 0 0
sp_reserve_write_off 9742 9742 0 0.00 9 23221 254 0 0
sp_supplier_invoice 5414 5414 0 0.00 8 93854 3272 0 0
sp_supplier_order 5348 5348 0 0.00 7 18805 1403 0 0
srv_aggregate_perf_data 120 120 0 0.00 2520 128589 15540 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 719 155001 34271 0 0
srv_find_qd_qs_mism 1016213 1016213 0 0.00 0 28242 11 0 0
srv_make_invnt_saldo 523 523 0 0.00 79 84485 3370 0 0
srv_make_money_saldo 904 904 0 0.00 1 10534 298 0 0
srv_recalc_idx_stat 5 5 0 0.00 62924 382961 195444 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5057 5057 0 0.00 1 12223 881 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 5484 404 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 26639 44 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1275 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 3165 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1078 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 742 29 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 81723 6651 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 653 17 0 0
t$perf-norm:sp_client_order 20916 20916 0 0.00 0 26737 34 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 3729 15 0 0
t$perf-norm:sp_pay_from_customer 6870 6870 0 0.00 0 28028 21 0 0
t$perf-norm:sp_pay_to_supplier 5648 5648 0 0.00 0 26583 21 0 0
t$perf-norm:sp_reserve_write_off 9743 9743 0 0.00 0 28385 27 0 0
t$perf-norm:sp_supplier_invoice 5414 5414 0 0.00 0 5009 31 0 0
t$perf-norm:sp_supplier_order 5348 5348 0 0.00 0 2491 29 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 156 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1380 22 0 0
t$perf-norm:srv_make_invnt_saldo 523 523 0 0.00 0 6212 29 0 0
t$perf-norm:srv_make_money_saldo 904 904 0 0.00 0 425 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 171 74 0 0
v_add_invoice_to_stock 10182 9988 194 1.90 0 5708 27 0 194
v_all_customers 37897 37897 0 0.00 0 138 3 0 0
v_all_suppliers 29846 29846 0 0.00 0 136 4 0 0
v_all_wares 41828 41828 0 0.00 0 124 4 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 1419 19 0 0
v_cancel_client_order 3480 3480 0 0.00 0 1739 9 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 1687 10 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 1 1966 12 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 767 11 0 0
v_cancel_supplier_invoice 750 746 4 0.53 0 365 18 0 4
v_cancel_supplier_order 790 790 0 0.00 0 727 9 0 0
v_cancel_write_off 1982 1982 0 0.00 0 7273 28 0 0
v_max_id_clo_ord 5346 5346 0 0.00 0 176 4 0 0
v_max_id_clo_res 954 954 0 0.00 0 2698 16 0 0
v_max_id_ord_sup 5404 5404 0 0.00 0 1908 5 0 0
v_max_non_paid_invoice 1473 1473 0 0.00 0 213 7 0 0
v_max_non_paid_realizn 3674 3674 0 0.00 0 520 6 0 0
v_min_id_clo_ord 5346 5346 0 0.00 0 6361 8 0 0
v_min_id_clo_res 954 954 0 0.00 0 3425 11 0 0
v_min_id_ord_sup 5404 5404 0 0.00 0 6328 13 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 3708 13 0 0
v_min_non_paid_realizn 6867 6867 0 0.00 0 7688 14 0 0
v_our_firm 10318 10318 0 0.00 0 152 4 0 0
v_reserve_write_off 19484 19484 0 0.00 0 2850 11 0 0
x_lock_depdocs_on_canc_invoice 10707 10707 0 0.00 0 20891 116 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 6120 316 0 0
x_make_qstorno_1000_1200 5348 5348 0 0.00 3 17120 1245 0 0
x_make_qstorno_1200_2000 5414 5414 0 0.00 3 93696 3039 0 0
x_make_qstorno_2100_3300 472497 472497 0 0.00 0 155905 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-05-2022 04:38:05 to 10-05-2022 04:38:09.

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
02:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 32659 8192000000 9728882400 10026856448 805489696 8.952 100 91 182 0 835849584 7933520 22426368
02:10:02 18075 8192000000 9776265536 10085695488 845342208 9.353 100 93 186 0 880562864 12738448 22482208
02:11:22 21587 8192000000 9817660624 10141302784 880084992 9.701 100 82 164 0 925495152 26387776 19022384
02:12:45 13747 8192000000 9877059680 10215133184 935158704 10.245 100 83 166 1 986653664 33314512 18180448
02:14:02 4805 8192000000 9944210528 10289623040 967596880 10.563 100 92 181 0 1053385856 66535296 19253680
02:15:13 1946 8192000000 9955158544 10308816896 999755344 10.876 100 90 174 0 1073943968 56854768 17333856
02:16:17 1199 8192000000 10023751216 10388471808 1022372352 11.095 100 95 188 0 1139846208 98631264 18842592
02:17:20 1436 8192000000 10012627264 10380091392 1038961408 11.255 100 93 182 0 1127548416 69924608 18662400
02:18:22 1134 8192000000 10008902672 10384023552 1054098784 11.400 100 89 172 2 1128958400 57543872 17315744
02:19:24 2206 8192000000 10026720880 10413273088 1062141392 11.477 100 84 163 0 1154162896 74619728 17401776
02:20:27 1558 8192000000 10038820560 10419458048 1066952944 11.523 100 85 165 1 1163612608 79767760 16891904
02:21:29 998 8192000000 10033443296 10421751808 1071493408 11.566 100 78 150 1 1153854912 66508784 15852720
02:22:32 1282 8192000000 10051228448 10440400896 1073677200 11.587 100 84 161 1 1172572576 82489872 16405504
02:23:34 1823 8192000000 10016830928 10407292928 1074594736 11.596 100 74 146 0 1140929680 51083616 15251328
02:24:37 1037 8192000000 10042706912 10425958400 1080061568 11.648 100 88 170 0 1165871040 68572080 17237392
02:25:40 4382 8192000000 10041702400 10439237632 1085702368 11.702 100 69 136 1 1174129824 75339872 13087584
02:26:45 869 8192000000 10030273248 10413166592 1082674224 11.673 100 91 175 0 1148756976 49213232 16869520
02:27:46 982 8192000000 10062370544 10452566016 1087472352 11.719 100 90 170 0 1184673440 79147328 18053760
02:28:48 1676 8192000000 10029432656 10421133312 1065981136 11.514 99 75 142 1 1154217664 72547616 15688912
02:29:50 6109 8192000000 10026094480 10413862912 1071388784 11.565 100 68 127 1 1141200816 56656640 13155392
02:30:57 728 8192000000 10037120688 10423078912 1073986960 11.590 100 86 165 0 1158928112 66690336 18250816
02:31:59 6699 8192000000 9998132928 10381185024 1027239920 11.142 99 62 119 2 1116940240 78695024 11005296
02:33:06 908 8192000000 9965038992 10331836416 1025471488 11.125 99 86 165 1 1083535776 41719536 16344752
02:34:07 1489 8192000000 9980095184 10359529472 1041036176 11.275 100 78 150 5 1104286976 47959040 15291760
02:35:10 1370 8192000000 9986743600 10356269056 1032195632 11.190 100 93 167 3 1107522368 57187200 18139536
02:36:12 7689 8192000000 9989934512 10369994752 1042079040 11.285 100 62 105 1 1119982272 67379264 10523968
02:37:20 926 8192000000 9993272064 10370064384 1052885424 11.388 100 91 170 2 1115967056 46810496 16271136
02:38:31 2967 8192000000 10003406736 10386190336 1065280560 11.507 100 72 130 0 1124966304 46877584 12808160
02:39:40 8664 8192000000 10059578048 10452537344 1078902432 11.637 100 73 143 0 1185217856 92125056 14190368
02:40:52 2423 8192000000 10073104224 10456068096 1100700032 11.844 100 88 168 1 1194413360 75885072 17828256
02:42:21 11524 8192000000 10105934752 10498252800 1109538080 11.928 100 65 129 0 1213548208 90414496 13595632
02:43:38 6529 8192000000 10129424416 10526728192 1124184448 12.067 100 65 126 2 1249639648 111834832 13620368
02:44:49 37077 8192000000 10183270112 10593132544 1139227632 12.208 100 59 110 2 1286571952 135065872 12278448
02:46:30 3867 8192000000 10199565552 10602348544 1151084448 12.320 100 84 165 1 1312933472 143428928 18420096
02:47:42 1195 8192000000 10265211616 10662797312 1167893008 12.477 100 93 177 2 1375831152 188397024 19541120
02:48:46 1028 8192000000 10293996064 10707722240 1180418608 12.594 100 86 167 0 1402753440 202910976 19423856
02:49:49 709 8192000000 10330760976 10738176000 1188241728 12.667 100 99 199 2 1444705504 232926032 23537744
02:50:53 961 8192000000 10349218592 10760364032 1192633344 12.708 100 94 184 0 1457537392 243696848 21207200
02:51:57 3096 8192000000 10388481088 10792460288 1203572832 12.809 100 85 161 0 1493657728 271960400 18124496
02:53:02 859 8192000000 10420283120 10839961600 1204891152 12.822 100 93 181 1 1525748800 299915984 20941664
02:54:05 1176 8192000000 10458712544 10874462208 1208152480 12.852 100 97 190 2 1572921344 341922288 22846576
02:55:10 1000 8192000000 10453905216 10917117952 1207581760 12.847 100 99 190 2 1561957584 331475616 22900208
02:56:13 1144 8192000000 10488193056 10895183872 1206738000 12.839 100 97 193 1 1594103088 364792368 22572720
02:57:20 932 8192000000 10476019232 10918326272 1201825728 12.793 100 96 186 1 1582806768 359245216 21735824
02:58:24 997 8192000000 10507540816 10940624896 1203392128 12.808 100 98 193 0 1612082208 386014368 22675712
02:59:27 2684 8192000000 10523996784 10945523712 1193707440 12.718 100 91 172 1 1628572448 414653872 20211136
03:00:36 1322 8192000000 10553748560 10986295296 1198970752 12.767 100 76 147 1 1657489424 440482400 18036272
03:01:41 6430 8192000000 10566025024 11004092416 1192099056 12.703 100 64 123 2 1673115424 465998192 15018176
03:02:49 14321 8192000000 10583913952 11050762240 1195845904 12.738 100 48 91 0 1689347568 481361264 12140400
03:04:13 11839 8192000000 10545665216 11013804032 1182956928 12.618 100 36 72 0 1655795376 462568288 10270160
03:05:34 36045 8192000000 10387668928 10948239360 1151912640 12.327 99 36 72 3 1579016608 417355552 9748416
03:07:16 36422 8192000000 10218658736 10670993408 1133462704 12.154 99 20 40 0 1305635488 167442640 4730144
03:09:02 82595 8192000000 10079536336 10570563584 1114844512 11.978 97 21 42 0 1192585008 73148704 4591792
03:11:28 24950 8192000000 10094093344 10524282880 1147887328 12.290 100 39 74 1 1200797952 45612624 7298000
03:12:54 59476 8192000000 10112717648 10563465216 1119848048 12.026 99 41 82 1 1190260528 60753088 9659392
03:15:43 30036 8192000000 10101374432 10570182656 1132748848 12.147 100 49 93 0 1192724400 49088608 10886944
03:17:38 13720 8192000000 10146578384 10574282752 1133780864 12.157 100 60 114 1 1231845312 85496464 12567984
03:18:55 14871 8192000000 10093656512 10529492992 1134738704 12.166 100 36 70 1 1197523056 54864112 7920240
03:20:10 28233 8192000000 10113935744 10565951488 1136356048 12.181 100 20 40 0 1214781440 73510240 4915152
03:21:47 154985 8192000000 10112955504 10570571776 1094277744 11.783 96 14 28 0 1148123936 50906496 2939696
03:25:25 54230 8192000000 10126652224 10576375808 1129679264 12.118 100 29 57 2 1205653136 69511760 6462112
03:27:30 45665 8192000000 10092060224 10530488320 1126004624 12.084 98 26 48 1 1196934864 65418336 5511904
03:29:29 55014 8192000000 10126328128 10603044864 1140869216 12.224 99 19 38 0 1222459680 77018608 4571856
03:31:43 49318 8192000000 10121106480 10587557888 1123117248 12.056 97 15 30 1 1223577056 97182816 3276992
03:33:32 99722 8192000000 10084022224 10563100672 1085978416 11.704 95 20 40 1 1166176416 76055488 4142512
03:36:13 37937 8192000000 10112971120 10556952576 1126392320 12.087 99 28 56 0 1212856752 80861536 5602896
03:38:19 52802 8192000000 10057195376 10499317760 1132586960 12.146 100 35 60 2 1169473792 30708624 6178208
03:40:13 33236 8192000000 10069538288 10536009728 1135538464 12.174 99 22 44 0 1180205888 40041152 4626272
03:42:23 69576 8192000000 10107996864 10562703360 1155104304 12.357 100 11 22 0 1215934384 58307344 2522736
03:44:32 46968 8192000000 10096716960 10545577984 1116834768 11.997 97 22 44 2 1174058928 52378768 4845392
03:46:20 32897 8192000000 10098845456 10543767552 1133767904 12.157 99 27 54 3 1201843200 61631312 6443984
03:47:56 61118 8192000000 10146012448 10621464576 1141049008 12.225 99 29 57 1 1221960976 73872976 7038992
03:49:57 66380 8192000000 10123928944 10617483264 1129580640 12.117 98 15 30 1 1234188800 101252832 3355328
03:52:07 66953 8192000000 10086352080 10510577664 1096021456 11.800 96 37 74 0 1131270048 27765712 7482880
03:54:17 42330 8192000000 10113599088 10562244608 1140374016 12.219 100 38 76 1 1220390784 72578016 7438752
03:56:52 36641 8192000000 10072515216 10496704512 1132629344 12.146 100 37 66 0 1161623232 21367392 7626496
03:58:31 83341 8192000000 10071819904 10553196544 1127049216 12.094 99 29 58 1 1204232416 71391200 5792000
04:01:01 3942 8192000000 10095785376 10533081088 1147685232 12.288 100 43 82 1 1197473152 41361568 8426352
04:02:05 64995 8192000000 10119946192 10579173376 1137276176 12.190 99 18 36 0 1204565712 63645984 3643552
04:05:13 36723 8192000000 10067459648 10517585920 1120244816 12.029 98 22 41 0 1166912608 43002176 3665616
04:06:51 11840 8192000000 10104807136 10561769472 1122564128 12.051 99 28 55 2 1190174768 61480592 6130048
04:08:09 49951 8192000000 10110782496 10562502656 1111341424 11.945 97 32 62 2 1194811840 76446832 7023584
04:10:01 37349 8192000000 10166047216 10628263936 1141887104 12.233 100 44 88 0 1264423664 112899696 9636864
04:11:45 60375 8192000000 10167586608 10645905408 1131057424 12.131 98 29 58 2 1257236960 119585488 6594048
04:13:49 58835 8192000000 10145450448 10585919488 1141094640 12.226 99 35 67 2 1209683408 61869296 6719472
04:15:55 10477 8192000000 10123413744 10545745920 1143943824 12.253 100 73 138 3 1217736128 59959568 13832736
04:17:07 64343 8192000000 10080252752 10539585536 1085943360 11.704 96 40 76 0 1156575680 62804080 7828240
04:19:18 86997 8192000000 10050883344 10481975296 1080488784 11.652 96 52 103 2 1140844384 49857584 10498016
04:21:46 34506 8192000000 10047918816 10526806016 1125817872 12.082 100 18 36 0 1185335312 56172272 3345168
04:23:24 67721 8192000000 10081282048 10562146304 1119226144 12.020 99 15 30 2 1184022944 61337040 3459760
04:25:32 43555 8192000000 10058818128 10573168640 1116210096 11.991 99 10 20 0 1173904672 55379440 2315136
04:27:16 76615 8192000000 10102143392 10539888640 1128758048 12.110 99 18 36 0 1172873840 40338096 3777696
04:29:42 17354 8192000000 10107497824 10549166080 1129286256 12.115 100 35 70 1 1206304640 69507152 7511232
04:31:07 29898 8192000000 10128359072 10582216704 1133534352 12.155 99 29 55 0 1214003552 74651936 5817264
04:32:46 51627 8192000000 10069639376 10521784320 1105007008 11.885 97 19 38 3 1174446416 65514720 3924688
04:34:39 24700 8192000000 10092130656 10521358336 1118870064 12.016 98 46 91 1 1165622592 37790256 8962272
04:36:04 62039 8192000000 10099225968 10552766464 1112750288 11.958 98 36 72 0 1195521760 75751792 7019680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-05-2022 04:38:11 to 10-05-2022 04:38:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-05-2022 04:38:28 to 10-05-2022 04:38:30.

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          217093
MON$OLDEST_ACTIVE               221289
MON$OLDEST_SNAPSHOT             221289
MON$NEXT_TRANSACTION            221289
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                       2027360
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:205: 
:206: mscs-test-161.budzdorov.ru    Tue May 10 04:37:17 2022
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
:209: 
:210: mscs-test-161.budzdorov.ru    Tue May 10 04:37:20 2022
:211:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:212: 
:213: 
:214: mscs-test-161.budzdorov.ru    Tue May 10 04:37:20 2022
:215:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:216: 
:217: 
:218: mscs-test-161.budzdorov.ru    Tue May 10 04:37:22 2022
:219:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:220: 
:221: 
:222: mscs-test-161.budzdorov.ru    Tue May 10 04:37:22 2022
:223:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:224: 
:225: 
:226: mscs-test-161.budzdorov.ru    Tue May 10 04:37:23 2022
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:228: 
--- end of diff output ---

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

10.05.22 04:38:31. 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-10 04:38:34 No severe PSQL-related problems occured
10.05.22 04:38:35. End of report.