Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.12.2022 04: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 14843660 kB
MemAvailable 30070172 kB
Buffers 20 kB
Cached 16824648 kB
SwapCached 3172 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-13 04:37:21
DTS_END                         2022-12-13 04:37:21.3030
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/43316
TRN_ID                          218937
ATT_ID                          652
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: 8609.32 29744 1033118
customer order: creation 160.44 495 19253
customer order: refuse 14.89 531 1787
order to supplier: creation 43.93 1504 5272
order to supplier: removal 3.32 174600 398
invoice (draft): creation 45.03 3569 5403
invoice (draft): removal 45.85 1185 5502
invoice accept: apply 40.44 28760 4853
invoice accept: cancel 41.86 1451 5023
customer reserve: creation 3772.99 272 452759
customer reserve: removal 4161.96 108 499435
realization accept: apply 78.90 299 9468
realization accept: cancel 74.29 197 8915
payment from customer: creation 57.08 204 6850
payment from customer: removal 5.53 116 664
payment to supplier: creation 46.68 218 5602
payment to supplier: removal 3.53 142 424
service: total inventory turnovers 3.85 4301 462
service: total monetary turnovers 7.79 393 935
service: aggregate perf. data 0.91 15697 109
service: refresh index statistics 0.03 360844 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-12-2022 04:39:01 to 13-12-2022 04:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8778
TEST_TIME 2 6362
TEST_TIME 3 5877
TEST_TIME 4 5076
TEST_TIME 5 4695
TEST_TIME 6 4560
TEST_TIME 7 4653
TEST_TIME 8 4872
TEST_TIME 9 5099
TEST_TIME 10 5346
TEST_TIME 11 5384
TEST_TIME 12 5246
TEST_TIME 13 5522
TEST_TIME 14 5782
TEST_TIME 15 6296
TEST_TIME 16 6277
TEST_TIME 17 6505
TEST_TIME 18 6488
TEST_TIME 19 6484
TEST_TIME 20 6785
TEST_TIME 21 7180
TEST_TIME 22 7291
TEST_TIME 23 7338
TEST_TIME 24 7413
TEST_TIME 25 7410
TEST_TIME 26 7917
TEST_TIME 27 8387
TEST_TIME 28 8696
TEST_TIME 29 8998
TEST_TIME 30 9235
TEST_TIME 31 9150
TEST_TIME 32 9914
TEST_TIME 33 10148
TEST_TIME 34 10159
TEST_TIME 35 10190
TEST_TIME 36 10389
TEST_TIME 37 10242
TEST_TIME 38 10046
TEST_TIME 39 9880
TEST_TIME 40 9726
TEST_TIME 41 9565
TEST_TIME 42 9485
TEST_TIME 43 9471
TEST_TIME 44 9502
TEST_TIME 45 9471
TEST_TIME 46 9423
TEST_TIME 47 9434
TEST_TIME 48 9488
TEST_TIME 49 9494
TEST_TIME 50 9419
TEST_TIME 51 9304
TEST_TIME 52 9357
TEST_TIME 53 9407
TEST_TIME 54 9329
TEST_TIME 55 9439
TEST_TIME 56 9520
TEST_TIME 57 9549
TEST_TIME 58 9519
TEST_TIME 59 9510
TEST_TIME 60 9476
TEST_TIME 61 9407
TEST_TIME 62 9483
TEST_TIME 63 9441
TEST_TIME 64 9409
TEST_TIME 65 9300
TEST_TIME 66 9256
TEST_TIME 67 9170
TEST_TIME 68 9100
TEST_TIME 69 9107
TEST_TIME 70 9073
TEST_TIME 71 9019
TEST_TIME 72 8976
TEST_TIME 73 9017
TEST_TIME 74 9071
TEST_TIME 75 9071
TEST_TIME 76 9022
TEST_TIME 77 9007
TEST_TIME 78 9016
TEST_TIME 79 9047
TEST_TIME 80 9016
TEST_TIME 81 9087
TEST_TIME 82 9133
TEST_TIME 83 9127
TEST_TIME 84 9113
TEST_TIME 85 9192
TEST_TIME 86 9142
TEST_TIME 87 9051
TEST_TIME 88 9013
TEST_TIME 89 9066
TEST_TIME 90 9015
TEST_TIME 91 8960
TEST_TIME 92 8904
TEST_TIME 93 8857
TEST_TIME 94 8858
TEST_TIME 95 8871
TEST_TIME 96 8908
TEST_TIME 97 8912
TEST_TIME 98 8926
TEST_TIME 99 8922
TEST_TIME 100 8960
TEST_TIME 101 8927
TEST_TIME 102 8953
TEST_TIME 103 8946
TEST_TIME 104 8966
TEST_TIME 105 8969
TEST_TIME 106 9000
TEST_TIME 107 8957
TEST_TIME 108 8920
TEST_TIME 109 8906
TEST_TIME 110 8853
TEST_TIME 111 8791
TEST_TIME 112 8784
TEST_TIME 113 8757
TEST_TIME 114 8743
TEST_TIME 115 8709
TEST_TIME 116 8725
TEST_TIME 117 8710
TEST_TIME 118 8694
TEST_TIME 119 8697
TEST_TIME 120 8633

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-12-2022 04:39:05 to 13-12-2022 04:39:08.

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 1449422 1449422 0 0.00 0 29008 15 0 0
doc_list_biud 1449407 1449407 0 0.00 0 42017 44 0 0
sp_add_invoice_to_stock 4998 4853 145 2.90 47 266793 28761 0 145
sp_cancel_adding_invoice 5023 5023 0 0.00 7 130194 1452 0 0
sp_cancel_client_order 1787 1787 0 0.00 5 22361 531 0 0
sp_cancel_customer_reserve 499435 499435 0 0.00 1 35192 109 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 1521 116 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 4 3088 142 0 0
sp_cancel_supplier_invoice 5519 5502 17 0.30 3 42021 1185 0 17
sp_cancel_supplier_order 398 398 0 0.00 14 1554488 174600 0 0
sp_cancel_write_off 8915 8915 0 0.00 1 12843 197 0 0
sp_client_order 19253 19253 0 0.00 5 24729 496 0 0
sp_customer_reserve 452759 452759 0 0.00 0 52116 273 0 0
sp_fill_shopping_cart 29912 29912 0 0.00 1 28783 89 0 0
sp_fill_shopping_cart_clo_res 452726 452726 0 0.00 0 18434 20 0 0
sp_get_clo_for_invoice 4813 4813 0 0.00 0 21448 293 0 0
sp_kill_cost_storno 15026 15026 0 0.00 0 11810 89 0 0
sp_kill_qstorno_ret_qs2qd 1013685 1013685 0 0.00 0 42016 48 0 0
sp_kill_qty_storno 535338 535338 0 0.00 0 42016 111 0 0
sp_lock_selected_doc 19909 19909 0 0.00 0 7613 8 0 0
sp_make_cost_storno 26732 26732 0 0.00 0 28318 86 0 0
sp_multiply_rows_for_pdistr 26729 26729 0 0.00 0 11337 39 0 0
sp_multiply_rows_for_qdistr 19252 19252 0 0.00 0 22423 193 0 0
sp_pay_from_customer 6850 6850 0 0.00 5 17772 204 0 0
sp_pay_to_supplier 5602 5602 0 0.00 6 17783 218 0 0
sp_payment_common 12447 12447 0 0.00 2 12519 49 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 1 22348 437 0 0
sp_qd_handle_on_invoice_upd_sts 9835 9835 0 0.00 0 40323 589 0 0
sp_qd_handle_on_reserve_upd_sts 9466 9466 0 0.00 0 18827 72 0 0
sp_reserve_write_off 9468 9468 0 0.00 9 18933 299 0 0
sp_supplier_invoice 5403 5403 0 0.00 9 84722 3569 0 0
sp_supplier_order 5272 5272 0 0.00 6 33221 1505 0 0
srv_aggregate_perf_data 109 109 0 0.00 3066 255927 15697 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 854 105329 30318 0 0
srv_find_qd_qs_mism 985623 985623 0 0.00 0 19762 12 0 0
srv_make_invnt_saldo 462 462 0 0.00 55 128702 4301 0 0
srv_make_money_saldo 935 935 0 0.00 1 14355 393 0 0
srv_recalc_idx_stat 4 4 0 0.00 252365 558530 360844 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 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 4855 4855 0 0.00 1 18775 954 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 2 7672 481 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 2755 29 0 0
t$perf-norm:sp_cancel_customer_reserve 610 610 0 0.00 0 2445 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 586 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 499 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 375 375 0 0.00 0 584 30 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 121732 7607 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 883 17 0 0
t$perf-norm:sp_client_order 19253 19253 0 0.00 0 15994 41 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 17085 32 0 0
t$perf-norm:sp_pay_from_customer 6851 6851 0 0.00 0 17654 25 0 0
t$perf-norm:sp_pay_to_supplier 5604 5604 0 0.00 0 23468 29 0 0
t$perf-norm:sp_reserve_write_off 9468 9468 0 0.00 0 11619 25 0 0
t$perf-norm:sp_supplier_invoice 5403 5403 0 0.00 0 6685 33 0 0
t$perf-norm:sp_supplier_order 5272 5272 0 0.00 0 3093 35 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 248 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 41 3 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 5657 32 0 0
t$perf-norm:srv_make_money_saldo 935 935 0 0.00 0 666 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 15 179 85 0 0
v_add_invoice_to_stock 9904 9614 290 2.92 0 6323 30 0 290
v_all_customers 35743 35743 0 0.00 0 127 4 0 0
v_all_suppliers 29522 29522 0 0.00 0 131 4 0 0
v_all_wares 38486 38486 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 131 14 0 0
v_cancel_client_order 3574 3574 0 0.00 0 17562 16 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 1481 13 0 0
v_cancel_customer_reserve 1220 1220 0 0.00 0 1659 13 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2110 18 0 0
v_cancel_supplier_invoice 784 750 34 4.33 0 1080 27 0 34
v_cancel_supplier_order 796 796 0 0.00 0 1958 18 0 0
v_cancel_write_off 1992 1992 0 0.00 0 12405 35 0 0
v_max_id_clo_ord 5266 5266 0 0.00 0 435 5 0 0
v_max_id_clo_res 991 991 0 0.00 0 2280 16 0 0
v_max_id_ord_sup 5398 5398 0 0.00 0 9158 11 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 557 8 0 0
v_max_non_paid_realizn 3421 3421 0 0.00 0 2201 8 0 0
v_min_id_clo_ord 5266 5266 0 0.00 0 3463 10 0 0
v_min_id_clo_res 991 991 0 0.00 0 2637 11 0 0
v_min_id_ord_sup 5398 5398 0 0.00 0 9938 12 0 0
v_min_non_paid_invoice 5598 5598 0 0.00 0 6519 18 0 0
v_min_non_paid_realizn 6849 6849 0 0.00 0 12509 20 0 0
v_our_firm 9598 9598 0 0.00 0 66 5 0 0
v_reserve_write_off 18927 18927 0 0.00 0 15147 14 0 0
x_lock_depdocs_on_canc_invoice 10150 10150 0 0.00 0 14744 147 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 14220 387 0 0
x_make_qstorno_1000_1200 5272 5272 0 0.00 3 33197 1324 0 0
x_make_qstorno_1200_2000 5403 5403 0 0.00 4 84706 3318 0 0
x_make_qstorno_2100_3300 452758 452758 0 0.00 0 43788 240 0 0

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

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:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:25 32581 8192000000 9729337296 10028920832 806284816 8.960 100 88 176 0 835501488 8089936 21126736
02:10:05 11152 8192000000 9761662512 10066518016 834619488 9.246 100 94 187 0 869361264 12697920 22043856
02:11:20 19082 8192000000 9790869232 10108149760 858213824 9.482 100 83 166 0 897465696 19381632 19870240
02:12:51 8053 8192000000 9857175680 10189492224 911947280 10.017 100 83 162 1 973540048 43597984 17994784
02:14:02 3255 8192000000 9915848160 10258841600 954740400 10.438 100 85 170 0 1034289872 62096560 17452912
02:15:09 1695 8192000000 9963417760 10318929920 994278048 10.823 100 93 182 0 1080141968 67501680 18362240
02:16:13 1566 8192000000 10000728848 10364993536 1023465872 11.105 100 95 181 2 1115673168 74222208 17985088
02:17:16 1099 8192000000 10024463200 10395561984 1045133696 11.314 100 96 191 1 1141432368 77224720 19073952
02:18:18 1059 8192000000 10052086352 10428309504 1058658112 11.444 100 95 191 0 1175571232 98407856 18505264
02:19:21 1218 8192000000 10045266064 10421596160 1065802032 11.512 100 99 183 2 1164800480 80375680 18622768
02:20:23 944 8192000000 10036803920 10419679232 1071333280 11.565 100 94 182 2 1160698176 69680832 19684064
02:21:25 1268 8192000000 10043744144 10432167936 1074858144 11.598 100 89 173 1 1165566912 72420416 18288352
02:22:34 859 8192000000 10055051456 10443259904 1081285136 11.660 100 91 181 1 1175602304 75398976 18918192
02:23:36 1096 8192000000 10050597664 10435530752 1085637904 11.701 100 94 185 1 1170458544 65357552 19463088
02:24:38 1055 8192000000 10039607472 10432524288 1087302192 11.717 100 78 150 3 1164966560 62026960 15637408
02:25:40 1133 8192000000 10065127280 10455289856 1092308096 11.765 100 87 169 1 1190945728 81563680 17073952
02:26:42 2740 8192000000 10054491184 10443997184 1084871424 11.694 99 75 142 2 1176465184 77157904 14435856
02:27:46 1307 8192000000 10046857536 10436169728 1091926752 11.761 100 95 185 2 1170040368 58901008 19212608
02:28:50 1112 8192000000 10057682480 10447966208 1091681904 11.759 100 94 175 0 1171428160 61796944 17949312
02:29:52 4387 8192000000 10033042512 10432024576 1081430880 11.661 99 63 125 0 1149245472 55175744 12638848
02:30:58 1113 8192000000 10029166288 10420097024 1072437680 11.575 99 92 170 1 1157684544 68040128 17206736
02:32:00 1469 8192000000 10010366416 10388901888 1049927888 11.360 100 89 171 1 1132909376 65413728 17567760
02:33:03 1720 8192000000 9980016416 10351742976 1021027904 11.082 98 81 158 1 1105398560 67923920 16446736
02:34:06 3616 8192000000 10000340576 10379567104 1042073168 11.285 100 77 145 1 1123146896 66801360 14272368
02:35:10 1807 8192000000 10007627232 10387132416 1036612704 11.232 100 75 141 2 1128988048 78312592 14062752
02:36:13 1029 8192000000 9980760544 10350821376 1020674240 11.079 100 97 179 0 1102904672 62940896 19289536
02:37:15 1360 8192000000 9974783184 10352996352 1033700768 11.204 100 85 158 3 1096736480 45348464 17687248
02:38:33 37711 8192000000 9998711680 10396147712 1036379264 11.230 99 59 113 1 1118940896 71426880 11134752
02:40:16 3378 8192000000 10006515712 10388963328 1066419056 11.518 100 72 125 0 1127244352 47806592 13018704
02:41:22 8268 8192000000 10057383792 10444787712 1080869248 11.656 100 65 121 0 1172672400 79193040 12610112
02:42:39 12582 8192000000 10115754352 10506858496 1096090240 11.801 100 60 120 0 1230016736 121151664 12774832
02:44:05 3207 8192000000 10176112256 10565001216 1105981472 11.894 100 88 172 1 1296335120 171843808 18509840
02:45:13 2797 8192000000 10211677520 10624278528 1117292992 12.001 100 69 134 0 1328717296 196809216 14615088
02:46:21 3096 8192000000 10220916688 10614587392 1126393344 12.087 100 77 152 0 1346475808 203609136 16473328
02:47:32 1413 8192000000 10265458192 10655834112 1149182368 12.302 100 92 181 1 1378254048 209567184 19504496
02:48:38 5814 8192000000 10285909376 10689351680 1158610304 12.390 100 74 142 1 1398110544 223718848 15781392
02:49:47 1970 8192000000 10338265008 10741194752 1169354864 12.491 100 76 150 0 1448631248 261574976 17701408
02:50:56 1722 8192000000 10356808320 10775650304 1175843568 12.551 100 92 179 0 1470767584 273579824 21344192
02:52:00 941 8192000000 10363922208 10803830784 1185839920 12.645 100 92 176 0 1470661968 263985376 20836672
02:53:02 1536 8192000000 10396596112 10799087616 1190727872 12.690 100 84 166 1 1503597856 293930320 18939664
02:54:08 920 8192000000 10417992928 10835251200 1191458256 12.697 100 94 181 2 1526735888 314007040 21270592
02:55:11 849 8192000000 10452719216 10857390080 1188374672 12.668 99 91 170 1 1556416272 348383008 19658592
02:56:14 1045 8192000000 10504649216 10911805440 1199764144 12.774 100 96 189 1 1613854768 391069680 23020944
02:57:18 982 8192000000 10490706000 10946207744 1198707296 12.764 100 95 185 1 1598289216 376423840 23158080
02:58:22 896 8192000000 10507334736 10949414912 1188957920 12.674 100 98 187 0 1612968368 401862720 22147728
02:59:24 868 8192000000 10500840288 10921390080 1163398032 12.435 100 90 174 2 1608773104 424556144 20818928
03:00:29 3263 8192000000 10544009680 10973761536 1171793232 12.514 99 98 194 2 1636857216 441856176 23207808
03:01:34 858 8192000000 10562861728 10976305152 1183787120 12.626 100 88 176 1 1670274576 464331696 22155760
03:02:40 1965 8192000000 10490224832 11000348672 1164913072 12.449 100 89 176 1 1596653632 408535248 23205312
03:03:46 1579 8192000000 10500729184 10966368256 1169632896 12.493 100 87 168 1 1608084640 416487904 21963840
03:04:50 18495 8192000000 10440116864 10905018368 1150254560 12.312 99 32 64 0 1549479360 390084736 9140064
03:06:11 35658 8192000000 10335914000 10784559104 1126832432 12.091 98 31 62 0 1433599664 298987488 7779744
03:07:51 17685 8192000000 10290313424 10759520256 1136983904 12.187 100 38 73 1 1397766448 251645408 9137136
03:09:11 15690 8192000000 10199495936 10728923136 1134421744 12.163 99 27 52 1 1311071488 170184928 6464816
03:10:28 29737 8192000000 10143816064 10647842816 1126718704 12.090 99 15 29 0 1267592448 137322544 3551200
03:11:59 77573 8192000000 10078446432 10605322240 1135162656 12.170 100 15 22 3 1239044032 101466784 2414592
03:14:19 52148 8192000000 10080894528 10537906176 1133462768 12.154 99 30 58 0 1180732688 40815744 6454176
03:17:16 64713 8192000000 10152084496 10589011968 1138781072 12.204 99 37 67 1 1212630496 67060608 6788816
03:19:32 28306 8192000000 10134923520 10587770880 1119536704 12.023 98 59 110 0 1207000528 76904304 10559520
03:21:01 9682 8192000000 10174283280 10630295552 1135366576 12.172 99 52 104 1 1282140064 135956288 10817200
03:22:16 1157 8192000000 10170227984 10593435648 1141125152 12.226 100 74 143 0 1266440896 110215392 15100352
03:23:21 32448 8192000000 10195370032 10643132416 1120134848 12.028 98 46 92 0 1261478832 131080416 10263568
03:25:10 36064 8192000000 10166928720 10681118720 1128463152 12.107 100 29 58 0 1262320640 126695584 7161904
03:26:51 40372 8192000000 10123154992 10591588352 1137177984 12.189 100 15 30 0 1231857568 91331184 3348400
03:28:32 34187 8192000000 10145773120 10624552960 1139812480 12.214 100 18 36 0 1277110128 133168656 4128992
03:30:15 31148 8192000000 10118834336 10615476224 1143042944 12.244 100 13 26 0 1245197008 98959264 3194800
03:31:48 22878 8192000000 10164512304 10619650048 1140428320 12.220 100 46 92 0 1244380336 94819680 9132336
03:33:13 39923 8192000000 10115805792 10588176384 1135344064 12.172 99 41 81 0 1210524032 67067424 8112544
03:34:55 40436 8192000000 10086374816 10547712000 1113837856 11.969 97 42 83 0 1175356176 52469200 9049120
03:36:45 51199 8192000000 10104900704 10556043264 1109565920 11.928 97 40 80 0 1166507600 49116320 7825360
03:38:45 58609 8192000000 10111946768 10587975680 1108903728 11.922 97 18 36 0 1183126064 70521600 3700736
03:40:55 13717 8192000000 10080090800 10508005376 1130290544 12.124 100 40 80 0 1156878096 18246144 8341408
03:42:12 87239 8192000000 10047624192 10503102464 1094746672 11.788 98 43 85 0 1148955008 45034704 9173632
03:45:25 1154 8192000000 10047848848 10468139008 1098088992 11.820 100 60 116 3 1153102960 42634256 12379712
03:46:27 41233 8192000000 10104176592 10574479360 1107480608 11.909 99 28 56 0 1213813136 100457024 5875504
03:48:09 51876 8192000000 10105727616 10567675904 1078681920 11.635 96 13 24 0 1173326624 92084816 2559888
03:50:01 61394 8192000000 10087630048 10554273792 1100890208 11.846 99 14 28 0 1197975376 93902032 3183136
03:52:03 73091 8192000000 10042822576 10481496064 1055472608 11.413 94 13 26 0 1091899760 33316160 3110992
03:54:16 35889 8192000000 10102957312 10535686144 1106374608 11.898 98 35 70 0 1186626288 73121440 7130240
03:55:54 38098 8192000000 10131694592 10561904640 1135263104 12.171 100 44 84 1 1216985200 72912400 8809696
03:57:45 44062 8192000000 10138458144 10618638336 1136292752 12.181 100 25 50 1 1265988912 124318464 5377696
03:59:34 29243 8192000000 10115051552 10558447616 1147244752 12.284 100 25 47 1 1210509712 58317408 4947552
04:01:06 12957 8192000000 10158362592 10591653888 1136034272 12.178 99 50 100 0 1236301248 90457680 9809296
04:02:22 19309 8192000000 10058668848 10482827264 1104833296 11.883 97 43 78 1 1141013840 28223568 7956976
04:03:42 81191 8192000000 10137431072 10584174592 1125360576 12.078 99 41 80 3 1227598464 93980128 8257760
04:06:39 56268 8192000000 10046539520 10559582208 1116190832 11.991 99 11 22 0 1158713936 40439152 2083952
04:08:36 22740 8192000000 10072801696 10556018688 1141369360 12.228 100 7 14 0 1180238576 37504896 1364320
04:09:59 41934 8192000000 10136192736 10587348992 1145774064 12.270 100 29 55 0 1226706816 75197728 5735024
04:11:48 67541 8192000000 10128976784 10601750528 1147317920 12.284 100 27 54 0 1246615600 93116912 6180768
04:13:57 92737 8192000000 10115760416 10557296640 1078114304 11.630 94 14 28 0 1133656448 52351728 3190416
04:16:44 53567 8192000000 10084358320 10569879552 1121916320 12.045 98 22 44 0 1198981040 72398368 4666352
04:18:50 14003 8192000000 10120163904 10543697920 1143612256 12.249 100 59 110 0 1206191616 51208672 11370688
04:20:05 54056 8192000000 10129242656 10586001408 1120127744 12.028 98 32 64 0 1198272432 71682720 6461968
04:22:00 34612 8192000000 10138959344 10599116800 1137475280 12.192 100 39 78 0 1216059712 70869216 7715216
04:23:37 105329 8192000000 10094513664 10607505408 1133002352 12.150 100 12 24 1 1209532016 73706608 2823056
04:26:27 66169 8192000000 10098962816 10579927040 1127628768 12.099 99 13 26 0 1195104544 64148128 3327648
04:28:36 48343 8192000000 10168804560 10659045376 1137880144 12.196 100 13 26 0 1277969200 137015056 3074000
04:31:44 44487 8192000000 10102674208 10549080064 1104919824 11.884 97 25 50 0 1176203440 64846992 6436624
04:33:31 35774 8192000000 10103428192 10550657024 1124316912 12.068 99 25 50 0 1195589984 65538016 5735056
04:35:11 41294 8192000000 10137253152 10592542720 1104604192 11.881 97 22 44 0 1214336688 104688752 5043744
04:36:57 38268 8192000000 9131829856 9467445248 271732944 3.210 25 6 12 0 346101024 72678416 1689664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-12-2022 04:39:16 to 13-12-2022 04:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 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 13-12-2022 04:39:33 to 13-12-2022 04:39:35.

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          212452
MON$OLDEST_ACTIVE               219309
MON$OLDEST_SNAPSHOT             219309
MON$NEXT_TRANSACTION            219309
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                       2048224
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:177: 
:178: mscs-test-161.budzdorov.ru    Tue Dec 13 04:37:38 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Tue Dec 13 04:37:39 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
:185: 
:186: mscs-test-161.budzdorov.ru    Tue Dec 13 04:37:39 2022
:187:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:188: 
:189: 
:190: mscs-test-161.budzdorov.ru    Tue Dec 13 04:37:39 2022
:191:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:192: 
--- end of diff output ---

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

13.12.22 04:39:36. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-13 04:39:39 No severe PSQL-related problems occured
13.12.22 04:39:40. End of report.