Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2022 10: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 15507636 kB
MemAvailable 30873552 kB
Buffers 0 kB
Cached 16170812 kB
SwapCached 2512 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-08-16 10:37:18
DTS_END                         2022-08-16 10:37:18.6020
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/50198
TRN_ID                          217019
ATT_ID                          593
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: 9058.67 27157 1087041
customer order: creation 171.43 465 20571
customer order: refuse 14.73 470 1767
order to supplier: creation 45.55 1420 5466
order to supplier: removal 3.42 187048 410
invoice (draft): creation 45.33 3220 5440
invoice (draft): removal 53.88 1002 6466
invoice accept: apply 40.17 25329 4821
invoice accept: cancel 47.98 1301 5757
customer reserve: creation 3690.01 242 442801
customer reserve: removal 4641.65 107 556998
realization accept: apply 82.36 299 9883
realization accept: cancel 93.38 174 11205
payment from customer: creation 59.13 183 7095
payment from customer: removal 5.72 131 686
payment to supplier: creation 48.58 195 5830
payment to supplier: removal 3.33 138 399
service: total inventory turnovers 4.04 4255 485
service: total monetary turnovers 6.98 381 837
service: aggregate perf. data 0.99 13944 119
service: refresh index statistics 0.04 302827 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2022 10:38:07 to 16-08-2022 10:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4984
TEST_TIME 2 4615
TEST_TIME 3 5559
TEST_TIME 4 5466
TEST_TIME 5 5304
TEST_TIME 6 5405
TEST_TIME 7 5653
TEST_TIME 8 5587
TEST_TIME 9 5315
TEST_TIME 10 5511
TEST_TIME 11 5714
TEST_TIME 12 5856
TEST_TIME 13 6128
TEST_TIME 14 6092
TEST_TIME 15 6408
TEST_TIME 16 6617
TEST_TIME 17 6924
TEST_TIME 18 7010
TEST_TIME 19 7060
TEST_TIME 20 7144
TEST_TIME 21 7103
TEST_TIME 22 7289
TEST_TIME 23 7177
TEST_TIME 24 7342
TEST_TIME 25 7452
TEST_TIME 26 7566
TEST_TIME 27 7813
TEST_TIME 28 7739
TEST_TIME 29 8064
TEST_TIME 30 8421
TEST_TIME 31 8861
TEST_TIME 32 9263
TEST_TIME 33 9755
TEST_TIME 34 10357
TEST_TIME 35 10428
TEST_TIME 36 10649
TEST_TIME 37 10826
TEST_TIME 38 10725
TEST_TIME 39 10582
TEST_TIME 40 10609
TEST_TIME 41 10531
TEST_TIME 42 10433
TEST_TIME 43 10507
TEST_TIME 44 10369
TEST_TIME 45 10434
TEST_TIME 46 10287
TEST_TIME 47 10166
TEST_TIME 48 10038
TEST_TIME 49 10022
TEST_TIME 50 9942
TEST_TIME 51 9854
TEST_TIME 52 9860
TEST_TIME 53 9786
TEST_TIME 54 9715
TEST_TIME 55 9663
TEST_TIME 56 9794
TEST_TIME 57 9743
TEST_TIME 58 9782
TEST_TIME 59 9698
TEST_TIME 60 9657
TEST_TIME 61 9573
TEST_TIME 62 9666
TEST_TIME 63 9678
TEST_TIME 64 9620
TEST_TIME 65 9634
TEST_TIME 66 9693
TEST_TIME 67 9658
TEST_TIME 68 9689
TEST_TIME 69 9635
TEST_TIME 70 9686
TEST_TIME 71 9742
TEST_TIME 72 9835
TEST_TIME 73 9768
TEST_TIME 74 9787
TEST_TIME 75 9764
TEST_TIME 76 9727
TEST_TIME 77 9788
TEST_TIME 78 9673
TEST_TIME 79 9626
TEST_TIME 80 9561
TEST_TIME 81 9547
TEST_TIME 82 9543
TEST_TIME 83 9487
TEST_TIME 84 9418
TEST_TIME 85 9366
TEST_TIME 86 9312
TEST_TIME 87 9248
TEST_TIME 88 9196
TEST_TIME 89 9374
TEST_TIME 90 9352
TEST_TIME 91 9339
TEST_TIME 92 9282
TEST_TIME 93 9272
TEST_TIME 94 9311
TEST_TIME 95 9319
TEST_TIME 96 9331
TEST_TIME 97 9280
TEST_TIME 98 9295
TEST_TIME 99 9352
TEST_TIME 100 9409
TEST_TIME 101 9417
TEST_TIME 102 9337
TEST_TIME 103 9291
TEST_TIME 104 9285
TEST_TIME 105 9235
TEST_TIME 106 9173
TEST_TIME 107 9130
TEST_TIME 108 9112
TEST_TIME 109 9062
TEST_TIME 110 9048
TEST_TIME 111 9060
TEST_TIME 112 9074
TEST_TIME 113 9112
TEST_TIME 114 9132
TEST_TIME 115 9108
TEST_TIME 116 9089
TEST_TIME 117 9086
TEST_TIME 118 9144
TEST_TIME 119 9146
TEST_TIME 120 9088

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2022 10:38:11 to 16-08-2022 10:38:14.

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 1496798 1496798 0 0.00 0 18542 13 0 0
doc_list_biud 1496787 1496787 0 0.00 0 43754 46 0 0
sp_add_invoice_to_stock 4916 4821 95 1.93 49 184493 25329 0 95
sp_cancel_adding_invoice 5757 5757 0 0.00 6 98207 1301 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 12557 471 0 0
sp_cancel_customer_reserve 556998 556998 0 0.00 1 43755 107 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 4 7552 131 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 4904 139 0 0
sp_cancel_supplier_invoice 6470 6466 4 0.06 3 17319 1003 0 4
sp_cancel_supplier_order 410 410 0 0.00 12 1724970 187048 0 0
sp_cancel_write_off 11205 11205 0 0.00 1 8410 174 0 0
sp_client_order 20571 20571 0 0.00 5 23978 466 0 0
sp_customer_reserve 442801 442801 0 0.00 0 93576 243 0 0
sp_fill_shopping_cart 31476 31476 0 0.00 1 18161 87 0 0
sp_fill_shopping_cart_clo_res 442775 442775 0 0.00 0 22436 17 0 0
sp_get_clo_for_invoice 4772 4772 0 0.00 0 30613 240 0 0
sp_kill_cost_storno 18047 18047 0 0.00 0 10278 79 0 0
sp_kill_qstorno_ret_qs2qd 1132077 1132077 0 0.00 0 43752 46 0 0
sp_kill_qty_storno 597253 597253 0 0.00 0 43754 106 0 0
sp_lock_selected_doc 20418 20418 0 0.00 0 10704 8 0 0
sp_make_cost_storno 27575 27575 0 0.00 0 12992 84 0 0
sp_multiply_rows_for_pdistr 27575 27575 0 0.00 0 12127 40 0 0
sp_multiply_rows_for_qdistr 20571 20571 0 0.00 0 9889 162 0 0
sp_pay_from_customer 7095 7095 0 0.00 5 15235 183 0 0
sp_pay_to_supplier 5830 5830 0 0.00 6 13659 196 0 0
sp_payment_common 12925 12925 0 0.00 2 14586 51 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 12549 380 0 0
sp_qd_handle_on_invoice_upd_sts 10524 10524 0 0.00 0 16054 544 0 0
sp_qd_handle_on_reserve_upd_sts 9883 9883 0 0.00 0 17903 68 0 0
sp_reserve_write_off 9883 9883 0 0.00 8 18802 300 0 0
sp_supplier_invoice 5440 5440 0 0.00 7 86309 3221 0 0
sp_supplier_order 5466 5466 0 0.00 7 23768 1420 0 0
srv_aggregate_perf_data 119 119 0 0.00 1872 185312 13945 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 688 131616 33534 0 0
srv_find_qd_qs_mism 1040026 1040026 0 0.00 0 13365 10 0 0
srv_make_invnt_saldo 485 485 0 0.00 38 155876 4256 0 0
srv_make_money_saldo 837 837 0 0.00 1 9799 382 0 0
srv_recalc_idx_stat 5 5 0 0.00 123301 445130 302828 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4822 4822 0 0.00 1 15323 946 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 1 8556 546 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 2389 30 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 9342 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 3588 40 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 543 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 9721 59 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 84771 8116 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 2024 19 0 0
t$perf-norm:sp_client_order 20571 20571 0 0.00 0 18225 39 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3497 21 0 0
t$perf-norm:sp_pay_from_customer 7095 7095 0 0.00 0 2532 19 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 5560 23 0 0
t$perf-norm:sp_reserve_write_off 9883 9883 0 0.00 0 5189 24 0 0
t$perf-norm:sp_supplier_invoice 5440 5440 0 0.00 0 3874 35 0 0
t$perf-norm:sp_supplier_order 5466 5466 0 0.00 0 5201 35 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 464 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 70 5 0 0
t$perf-norm:srv_make_invnt_saldo 485 485 0 0.00 0 10970 48 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 714 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 76 36 0 0
v_add_invoice_to_stock 9724 9534 190 1.95 0 6650 27 0 190
v_all_customers 37728 37728 0 0.00 0 98 4 0 0
v_all_suppliers 30652 30652 0 0.00 0 82 4 0 0
v_all_wares 41142 41142 0 0.00 0 158 4 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 112 13 0 0
v_cancel_client_order 3534 3534 0 0.00 0 8323 15 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 959 11 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 1 1388 12 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 2084 14 0 0
v_cancel_supplier_invoice 864 856 8 0.92 0 557 26 0 8
v_cancel_supplier_order 820 820 0 0.00 0 1846 13 0 0
v_cancel_write_off 1980 1980 0 0.00 0 5797 25 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 763 5 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 2050 12 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 7436 8 0 0
v_max_non_paid_invoice 1409 1409 0 0.00 0 167 8 0 0
v_max_non_paid_realizn 3666 3666 0 0.00 0 1469 8 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 2384 9 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 4632 14 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 10149 17 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 6458 18 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 14579 22 0 0
v_our_firm 10272 10272 0 0.00 0 106 5 0 0
v_reserve_write_off 19766 19766 0 0.00 0 7120 11 0 0
x_lock_depdocs_on_canc_invoice 11795 11795 0 0.00 0 15645 133 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 24680 485 0 0
x_make_qstorno_1000_1200 5466 5466 0 0.00 3 23653 1253 0 0
x_make_qstorno_1200_2000 5440 5440 0 0.00 3 85801 2968 0 0
x_make_qstorno_2100_3300 442800 442800 0 0.00 0 93541 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2022 10:38:15 to 16-08-2022 10:38:19.

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
08:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 24225 8192000000 9722808400 10024132608 807302480 8.970 100 87 174 0 836155808 7830704 21022624
08:09:58 8570 8192000000 9768373632 10080423936 845640720 9.356 100 85 170 0 877694816 12100512 19953584
08:11:11 13637 8192000000 9803618832 10122735616 873235328 9.632 100 87 173 0 911319056 18084672 19999056
08:12:34 15101 8192000000 9859271824 10190307328 910944912 10.007 100 83 164 0 971563808 42268000 18350896
08:13:53 4768 8192000000 9907307568 10249695232 950346336 10.394 100 83 159 0 1016947328 49793664 16807328
08:14:59 2671 8192000000 9942387504 10293325824 983122816 10.715 100 90 176 1 1063138128 61586560 18428752
08:16:04 1668 8192000000 9996527760 10352943104 1010357408 10.979 100 89 173 0 1108952096 80892928 17701760
08:17:08 1011 8192000000 9994758656 10364276736 1034087008 11.208 100 88 172 1 1119279040 68739248 16452784
08:18:11 1366 8192000000 10034708800 10412376064 1051640832 11.376 100 95 183 1 1158873072 89096912 18135328
08:19:14 1368 8192000000 10043241856 10420490240 1057944096 11.437 100 95 185 5 1166103056 89538272 18620688
08:20:18 1440 8192000000 10030007520 10412761088 1068517040 11.538 100 97 176 1 1145488528 58698208 18273280
08:21:20 1040 8192000000 10045963088 10425757696 1076967280 11.619 100 97 196 3 1163348016 66438752 19941984
08:22:22 1623 8192000000 10049004176 10437373952 1078624704 11.634 100 74 147 0 1171725152 79163648 13936800
08:23:26 1547 8192000000 10066849568 10451230720 1087492000 11.719 100 95 178 0 1187688448 82047824 18148624
08:24:28 1656 8192000000 10064681072 10452258816 1094632480 11.787 100 89 174 1 1187075856 74229264 18214112
08:25:32 2229 8192000000 10054308800 10442874880 1093273104 11.774 100 80 151 2 1180499520 72685872 14540544
08:26:37 2136 8192000000 10076857408 10464657408 1097394224 11.813 100 74 144 2 1194076016 81531504 15150288
08:27:40 5814 8192000000 10032739936 10431504384 1097515808 11.814 100 70 133 1 1167224544 54944960 14763776
08:28:47 1473 8192000000 10052350320 10440024064 1097416688 11.813 100 90 178 3 1171666736 55996832 18253216
08:29:50 1231 8192000000 10047090256 10433261568 1089248720 11.736 100 98 182 3 1171373056 63805760 18318576
08:30:53 1297 8192000000 10023407840 10400849920 1055507600 11.413 100 94 174 0 1142961600 68907408 18546592
08:31:56 1021 8192000000 10045676688 10427068416 1077803728 11.627 100 95 186 0 1169625440 72033552 19788160
08:32:57 1152 8192000000 10031032944 10412486656 1069833296 11.550 100 93 179 2 1155320928 67454864 18032768
08:34:00 1533 8192000000 9972612848 10343350272 1024923312 11.120 98 77 148 0 1091098960 51421984 14753664
08:35:03 3715 8192000000 9991205952 10372714496 1042003776 11.284 100 75 142 1 1115560800 59264368 14292656
08:36:08 1118 8192000000 10044205760 10418991104 1049214848 11.353 100 95 185 0 1166735216 98833520 18686848
08:37:11 2060 8192000000 10011107776 10389082112 1061233456 11.468 100 79 152 1 1136222640 59457776 15531408
08:38:20 6004 8192000000 10006642112 10390732800 1065636592 11.510 100 68 123 0 1133789072 56545680 11606800
08:39:41 13839 8192000000 10061301632 10450477056 1091940816 11.761 100 65 128 2 1179284096 74303616 13039664
08:41:00 13118 8192000000 10092863072 10483449856 1098439392 11.823 100 67 130 0 1206952736 94777728 13735616
08:42:16 8745 8192000000 10124258320 10521157632 1117913056 12.007 100 56 110 1 1240998704 110797200 12288448
08:43:29 970 8192000000 10165522080 10555756544 1128562928 12.108 100 90 171 2 1278982192 131983280 18435984
08:44:37 3813 8192000000 10168043520 10566336512 1133438560 12.154 100 72 135 0 1288094960 140318528 14337872
08:45:49 5174 8192000000 10195719808 10587865088 1123681696 12.062 98 83 160 1 1306802288 166749872 16370720
08:46:58 1241 8192000000 10283669984 10680352768 1152444992 12.332 100 91 176 1 1395496784 223530960 19520832
08:48:02 1308 8192000000 10307246976 10705563648 1163021888 12.432 100 91 175 3 1416010576 234183504 18805184
08:49:07 1475 8192000000 10336053664 10751799296 1179025680 12.581 100 90 174 0 1446217712 247803792 19388240
08:50:14 1719 8192000000 10363275040 10787602432 1186047136 12.647 100 89 166 1 1475731920 270554064 19130720
08:51:19 782 8192000000 10394541328 10801221632 1187063792 12.656 100 94 179 1 1508432960 298702928 22666240
08:52:26 2429 8192000000 10378274528 10818920448 1188587248 12.670 100 71 135 2 1483688032 278914720 16186064
08:53:32 1228 8192000000 10397133632 10824261632 1195495280 12.734 100 99 190 0 1507849456 288365776 23988400
08:54:37 1952 8192000000 10448174640 10883129344 1205697936 12.829 100 91 182 1 1563870192 336569744 21602512
08:55:43 712 8192000000 10467210576 10883035136 1204047536 12.814 100 93 185 1 1575852560 349587232 22217792
08:56:47 941 8192000000 10490391024 10911911936 1203611904 12.810 100 95 186 0 1598995376 372588000 22795472
08:57:52 1440 8192000000 10535156208 10956423168 1211237648 12.881 100 97 185 2 1639693472 406280896 22174928
08:58:57 1211 8192000000 10573825392 11007205376 1210765152 12.876 100 97 189 1 1676783840 443185536 22833152
09:00:00 909 8192000000 10598223936 11007524864 1197390704 12.752 100 87 170 0 1700724768 483069216 20264848
09:01:11 827 8192000000 10642195600 11096219648 1213695424 12.903 100 100 194 1 1745706272 508010112 24000736
09:02:14 694 8192000000 10647092944 11063562240 1204565632 12.819 100 85 170 1 1748693904 523598944 20529328
09:03:21 679 8192000000 10692077904 11145777152 1216410896 12.928 100 90 178 1 1794551104 554835728 23304480
09:04:32 5083 8192000000 10657521552 11100307456 1187587312 12.661 100 59 118 1 1765007040 561759056 15660672
09:05:50 5306 8192000000 10663082000 11152027648 1190322240 12.686 100 58 116 0 1765469008 560067728 15079040
09:06:58 4765 8192000000 10659730192 11161255936 1190382160 12.687 100 59 119 0 1765165216 559378864 15404192
09:08:15 14156 8192000000 10521387104 10992693248 1175863904 12.552 100 35 68 1 1645541664 460435440 9242320
09:09:34 19448 8192000000 10433561216 10926223360 1168035392 12.478 99 30 59 0 1556602176 381358256 7208528
09:10:54 10533 8192000000 10317253904 10816835584 1138886192 12.205 98 26 50 0 1452520816 307721072 5913552
09:12:42 43688 8192000000 10135629312 10642227200 1139139632 12.207 98 24 48 0 1245541440 100673648 5728160
09:14:27 89422 8192000000 10080524944 10551873536 1104596304 11.881 96 12 24 0 1141510640 34678864 2235472
09:17:56 22788 8192000000 10038182160 10465185792 1103299664 11.869 97 39 69 1 1130521392 20085216 7136512
09:19:26 131613 8192000000 10090957792 10522636288 1099640608 11.834 96 28 54 0 1144166992 38994320 5532064
09:22:42 70650 8192000000 10064774832 10495270912 1114952208 11.979 97 22 40 0 1152171520 33047344 4171968
09:24:54 51741 8192000000 10091232480 10522181632 1122702832 12.053 98 42 78 2 1170651808 39059184 8889792
09:26:47 44152 8192000000 10109689216 10558464000 1147604784 12.287 100 53 103 1 1210432752 51986912 10841056
09:28:47 19097 8192000000 10116823264 10543034368 1151822304 12.327 100 67 126 2 1218764576 52493472 14448800
09:30:07 32357 8192000000 10113416096 10555068416 1140622400 12.221 99 23 46 0 1199043552 53121344 5299808
09:31:46 16098 8192000000 10177656832 10615218176 1138267872 12.199 99 43 82 1 1257055008 110054928 8732208
09:33:10 33304 8192000000 10148489648 10591797248 1147499920 12.286 99 38 71 1 1248119472 93215952 7403600
09:34:45 67429 8192000000 10117785184 10577858560 1134896064 12.167 98 25 44 0 1213187664 73796256 4495344
09:36:53 65184 8192000000 10138416432 10614460416 1130395872 12.125 98 14 28 0 1226363680 92626128 3341680
09:38:59 19652 8192000000 10082124416 10559963136 1104981344 11.885 96 14 28 1 1180031600 71460224 3590032
09:40:23 81392 8192000000 10103194640 10587738112 1124553488 12.070 98 11 22 0 1161019168 34292656 2173024
09:42:45 44654 8192000000 10103475808 10551496704 1137529392 12.192 100 25 48 0 1195916848 53083008 5304448
09:44:30 25311 8192000000 10119720448 10565169152 1140856368 12.224 100 30 54 0 1197768256 51620240 5291648
09:45:56 32305 8192000000 10149516240 10583744512 1126062640 12.084 98 30 60 0 1207919296 75736240 6120416
09:47:33 33813 8192000000 10154011744 10595491840 1130385328 12.125 98 26 50 0 1224578992 88883200 5310464
09:49:09 46705 8192000000 10090352848 10577223680 1104273936 11.878 97 22 44 0 1185020400 76153520 4592944
09:51:01 14470 8192000000 10091756896 10531983360 1095203456 11.792 97 51 98 0 1155818288 50876688 9738144
09:52:25 31776 8192000000 10116043824 10553876480 1112324048 11.954 98 33 66 0 1191299184 72389232 6585904
09:53:57 93475 8192000000 10048943616 10530336768 1113522400 11.966 99 10 20 1 1149994912 33950720 2521792
09:56:39 58626 8192000000 10084169808 10531434496 1125544288 12.079 99 43 85 0 1177300992 42780432 8976272
09:58:39 58327 8192000000 10067502624 10551341056 1126691424 12.090 99 21 41 0 1179229568 48386240 4151904
10:00:37 14318 8192000000 10035725104 10480173056 1109147136 11.924 99 39 64 2 1140007616 24260560 6599920
10:02:03 95498 8192000000 10128101008 10582441984 1129020768 12.112 99 27 54 0 1222130800 86850624 6259408
10:04:38 95975 8192000000 10165992880 10631856128 1117474816 12.003 98 15 26 0 1251810928 130594416 3741696
10:07:18 75828 8192000000 10072841232 10516824064 1092389600 11.765 97 18 36 0 1130351696 34276208 3685888
10:09:35 8445 8192000000 10087057200 10516463616 1125010752 12.074 100 71 136 2 1186093168 46105376 14977040
10:10:46 108295 8192000000 10093438256 10596978688 1113586192 11.966 98 17 34 0 1166999824 49916144 3497488
10:13:34 38628 8192000000 10123278016 10561826816 1146051936 12.272 100 37 74 0 1214106560 60374336 7680288
10:15:16 45036 8192000000 10156830592 10622476288 1125061888 12.075 98 25 50 0 1224512128 94097664 5352576
10:17:09 115025 8192000000 10062543040 10562379776 1094671088 11.787 96 7 14 0 1167104768 70736672 1697008
10:20:18 13963 8192000000 10068722544 10514841600 1136617824 12.184 100 52 93 1 1168042656 22760272 8664560
10:21:32 52693 8192000000 10060101536 10519875584 1124798944 12.072 99 19 34 2 1158848192 30628832 3420416
10:23:25 42752 8192000000 10110716208 10573660160 1143287072 12.246 100 14 28 0 1206521744 59966720 3267952
10:27:03 104189 8192000000 10142302960 10630770688 1137502304 12.192 99 20 40 0 1235068784 93180928 4385552
10:29:48 59078 8192000000 10071508048 10516156416 1119851072 12.026 98 23 46 1 1195680368 70868048 4961248
10:31:47 49273 8192000000 10120927760 10567847936 1148854032 12.299 100 27 56 0 1208590096 53747456 5988608
10:33:37 26045 8192000000 10126550928 10569035776 1123033712 12.056 98 40 79 2 1203916544 72416752 8466080
10:35:04 77075 8192000000 10104739584 10542592000 1116679168 11.996 98 23 46 0 1157942128 37016272 4246688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-08-2022 10:38:21 to 16-08-2022 10:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-08-2022 10:38:38 to 16-08-2022 10:38:40.

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          214819
MON$OLDEST_ACTIVE               217899
MON$OLDEST_SNAPSHOT             217899
MON$NEXT_TRANSACTION            217899
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                       2070688
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             766
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2817 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2817 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2817 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2817 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Aug 16 10:37:23 2022
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Aug 16 10:37:23 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
--- end of diff output ---

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

16.08.22 10:38:41. 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-08-16 10:38:43 No severe PSQL-related problems occured
16.08.22 10:38:45. End of report.