Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.07.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 15632416 kB
MemAvailable 31091708 kB
Buffers 0 kB
Cached 16027864 kB
SwapCached 2532 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-07-09 10:37:17
DTS_END                         2022-07-09 10:37:17.0000
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/49512
TRN_ID                          221586
ATT_ID                          659
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: 9142.12 34041 1097054
customer order: creation 166.88 508 20026
customer order: refuse 14.98 484 1797
order to supplier: creation 46.37 1358 5564
order to supplier: removal 3.49 183371 419
invoice (draft): creation 46.40 3144 5568
invoice (draft): removal 51.84 1087 6221
invoice accept: apply 41.83 26800 5020
invoice accept: cancel 46.52 1363 5582
customer reserve: creation 3888.78 252 466653
customer reserve: removal 4534.48 111 544138
realization accept: apply 82.83 312 9940
realization accept: cancel 87.28 171 10474
payment from customer: creation 59.86 198 7183
payment from customer: removal 6.01 135 721
payment to supplier: creation 48.58 212 5830
payment to supplier: removal 3.30 140 396
service: total inventory turnovers 3.65 5022 438
service: total monetary turnovers 8.19 376 983
service: aggregate perf. data 0.81 18796 97
service: refresh index statistics 0.03 436989 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8131
TEST_TIME 2 5634
TEST_TIME 3 5439
TEST_TIME 4 5986
TEST_TIME 5 5548
TEST_TIME 6 5359
TEST_TIME 7 5201
TEST_TIME 8 5288
TEST_TIME 9 5513
TEST_TIME 10 5923
TEST_TIME 11 6272
TEST_TIME 12 6495
TEST_TIME 13 6744
TEST_TIME 14 6709
TEST_TIME 15 6861
TEST_TIME 16 6901
TEST_TIME 17 6924
TEST_TIME 18 6848
TEST_TIME 19 6905
TEST_TIME 20 7275
TEST_TIME 21 7317
TEST_TIME 22 7425
TEST_TIME 23 7437
TEST_TIME 24 7580
TEST_TIME 25 7707
TEST_TIME 26 7882
TEST_TIME 27 8118
TEST_TIME 28 8273
TEST_TIME 29 8525
TEST_TIME 30 8521
TEST_TIME 31 8722
TEST_TIME 32 9002
TEST_TIME 33 9596
TEST_TIME 34 9494
TEST_TIME 35 9724
TEST_TIME 36 10221
TEST_TIME 37 10715
TEST_TIME 38 10939
TEST_TIME 39 10722
TEST_TIME 40 10742
TEST_TIME 41 10693
TEST_TIME 42 10509
TEST_TIME 43 10422
TEST_TIME 44 10248
TEST_TIME 45 10269
TEST_TIME 46 10277
TEST_TIME 47 10243
TEST_TIME 48 10103
TEST_TIME 49 9975
TEST_TIME 50 9962
TEST_TIME 51 9923
TEST_TIME 52 9878
TEST_TIME 53 9860
TEST_TIME 54 9787
TEST_TIME 55 9801
TEST_TIME 56 9815
TEST_TIME 57 9798
TEST_TIME 58 9842
TEST_TIME 59 9712
TEST_TIME 60 9640
TEST_TIME 61 9581
TEST_TIME 62 9550
TEST_TIME 63 9566
TEST_TIME 64 9460
TEST_TIME 65 9380
TEST_TIME 66 9322
TEST_TIME 67 9379
TEST_TIME 68 9294
TEST_TIME 69 9280
TEST_TIME 70 9254
TEST_TIME 71 9270
TEST_TIME 72 9367
TEST_TIME 73 9378
TEST_TIME 74 9494
TEST_TIME 75 9461
TEST_TIME 76 9510
TEST_TIME 77 9462
TEST_TIME 78 9556
TEST_TIME 79 9632
TEST_TIME 80 9607
TEST_TIME 81 9547
TEST_TIME 82 9516
TEST_TIME 83 9453
TEST_TIME 84 9384
TEST_TIME 85 9365
TEST_TIME 86 9336
TEST_TIME 87 9310
TEST_TIME 88 9285
TEST_TIME 89 9209
TEST_TIME 90 9150
TEST_TIME 91 9129
TEST_TIME 92 9120
TEST_TIME 93 9111
TEST_TIME 94 9174
TEST_TIME 95 9128
TEST_TIME 96 9155
TEST_TIME 97 9144
TEST_TIME 98 9154
TEST_TIME 99 9149
TEST_TIME 100 9142
TEST_TIME 101 9242
TEST_TIME 102 9269
TEST_TIME 103 9296
TEST_TIME 104 9217
TEST_TIME 105 9173
TEST_TIME 106 9149
TEST_TIME 107 9159
TEST_TIME 108 9157
TEST_TIME 109 9117
TEST_TIME 110 9105
TEST_TIME 111 9064
TEST_TIME 112 9048
TEST_TIME 113 9051
TEST_TIME 114 9037
TEST_TIME 115 9055
TEST_TIME 116 9131
TEST_TIME 117 9233
TEST_TIME 118 9209
TEST_TIME 119 9213
TEST_TIME 120 9161

Performance per minute, chart


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

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 1526305 1526305 0 0.00 0 13665 14 0 0
doc_list_biud 1526301 1526301 0 0.00 0 24643 46 0 0
sp_add_invoice_to_stock 5140 5020 120 2.33 62 184658 26801 0 120
sp_cancel_adding_invoice 5582 5582 0 0.00 6 89453 1363 0 0
sp_cancel_client_order 1797 1797 0 0.00 7 15188 484 0 0
sp_cancel_customer_reserve 544138 544138 0 0.00 1 24646 112 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 4 4144 136 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 2341 141 0 0
sp_cancel_supplier_invoice 6228 6221 7 0.11 3 14805 1087 0 7
sp_cancel_supplier_order 419 419 0 0.00 16 1662762 183372 0 0
sp_cancel_write_off 10474 10474 0 0.00 2 7889 171 0 0
sp_client_order 20026 20026 0 0.00 6 19716 508 0 0
sp_customer_reserve 466653 466653 0 0.00 0 37006 252 0 0
sp_fill_shopping_cart 31158 31158 0 0.00 1 9390 83 0 0
sp_fill_shopping_cart_clo_res 466640 466640 0 0.00 0 17107 19 0 0
sp_get_clo_for_invoice 4984 4984 0 0.00 0 18388 257 0 0
sp_kill_cost_storno 17173 17173 0 0.00 0 7853 75 0 0
sp_kill_qstorno_ret_qs2qd 1105390 1105390 0 0.00 0 24642 48 0 0
sp_kill_qty_storno 583553 583553 0 0.00 0 24643 111 0 0
sp_lock_selected_doc 20793 20793 0 0.00 0 9465 8 0 0
sp_make_cost_storno 27935 27935 0 0.00 0 13551 85 0 0
sp_multiply_rows_for_pdistr 27935 27935 0 0.00 0 9411 41 0 0
sp_multiply_rows_for_qdistr 20026 20026 0 0.00 0 18573 197 0 0
sp_pay_from_customer 7183 7183 0 0.00 5 13772 199 0 0
sp_pay_to_supplier 5830 5830 0 0.00 6 17005 212 0 0
sp_payment_common 13013 13013 0 0.00 2 6273 45 0 0
sp_qd_handle_on_cancel_clo 1797 1797 0 0.00 1 15177 390 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 15825 595 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 16105 69 0 0
sp_reserve_write_off 9940 9940 0 0.00 8 23583 312 0 0
sp_supplier_invoice 5568 5568 0 0.00 9 61694 3144 0 0
sp_supplier_order 5564 5564 0 0.00 8 20720 1358 0 0
srv_aggregate_perf_data 97 97 0 0.00 1885 164620 18797 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 736 120695 29289 0 0
srv_find_qd_qs_mism 1047868 1047868 0 0.00 0 10559 10 0 0
srv_make_invnt_saldo 438 438 0 0.00 77 57180 5022 0 0
srv_make_money_saldo 983 983 0 0.00 1 17067 376 0 0
srv_recalc_idx_stat 4 4 0 0.00 215643 608876 436989 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5020 5020 0 0.00 0 10621 1026 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 2 6818 520 0 0
t$perf-norm:sp_cancel_client_order 1797 1797 0 0.00 0 1441 29 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 898 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 4369 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 441 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 3213 36 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 106327 7321 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 1828 22 0 0
t$perf-norm:sp_client_order 20026 20026 0 0.00 0 4950 39 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 1173 16 0 0
t$perf-norm:sp_pay_from_customer 7183 7183 0 0.00 0 4672 20 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 6974 24 0 0
t$perf-norm:sp_reserve_write_off 9940 9940 0 0.00 0 7982 24 0 0
t$perf-norm:sp_supplier_invoice 5568 5568 0 0.00 0 3894 34 0 0
t$perf-norm:sp_supplier_order 5564 5564 0 0.00 0 4086 34 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 1375 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 20 4 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 991 20 0 0
t$perf-norm:srv_make_money_saldo 983 983 0 0.00 0 388 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 19 177 62 0 0
v_add_invoice_to_stock 10204 9964 240 2.35 0 12784 32 0 240
v_all_customers 36966 36966 0 0.00 0 74 4 0 0
v_all_suppliers 30976 30976 0 0.00 0 76 5 0 0
v_all_wares 40052 40052 0 0.00 0 167 4 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 11204 32 0 0
v_cancel_client_order 3594 3594 0 0.00 0 4063 15 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 4059 18 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2556 16 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2265 18 0 0
v_cancel_supplier_invoice 866 852 14 1.61 0 9100 34 0 14
v_cancel_supplier_order 838 838 0 0.00 0 19407 57 0 0
v_cancel_write_off 2024 2024 0 0.00 0 1764 28 0 0
v_max_id_clo_ord 5564 5564 0 0.00 0 511 5 0 0
v_max_id_clo_res 986 986 0 0.00 0 2071 24 0 0
v_max_id_ord_sup 5568 5568 0 0.00 0 2612 7 0 0
v_max_non_paid_invoice 1474 1474 0 0.00 0 124 8 0 0
v_max_non_paid_realizn 3664 3664 0 0.00 0 1805 9 0 0
v_min_id_clo_ord 5564 5564 0 0.00 0 3098 10 0 0
v_min_id_clo_res 986 986 0 0.00 0 7127 15 0 0
v_min_id_ord_sup 5568 5568 0 0.00 0 5645 8 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 3696 16 0 0
v_min_non_paid_realizn 7183 7183 0 0.00 0 6269 16 0 0
v_our_firm 10124 10124 0 0.00 0 89 5 0 0
v_reserve_write_off 19880 19880 0 0.00 0 23238 19 0 0
x_lock_depdocs_on_canc_invoice 11377 11377 0 0.00 0 19154 129 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 16150 421 0 0
x_make_qstorno_1000_1200 5564 5564 0 0.00 3 20709 1174 0 0
x_make_qstorno_1200_2000 5568 5568 0 0.00 4 61677 2935 0 0
x_make_qstorno_2100_3300 466653 466653 0 0.00 0 37002 220 0 0

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

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:20 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 24202 8192000000 9732390480 10032857088 803230144 8.929 100 82 164 0 830786144 7453072 20102928
08:09:52 25174 8192000000 9776066320 10089332736 846377088 9.364 100 88 176 0 879234528 12092400 20765040
08:11:20 11022 8192000000 9797348320 10115969024 868974320 9.590 100 88 175 0 907575328 18125744 20475264
08:12:37 10519 8192000000 9857063008 10192068608 907855168 9.976 100 81 159 0 967121872 41204720 18061984
08:13:52 6467 8192000000 9910831168 10251886592 947773648 10.369 100 86 171 1 1030697664 64787328 18136688
08:15:00 2223 8192000000 9937451120 10286317568 991217728 10.793 100 90 177 0 1058651504 48849152 18584624
08:16:05 1529 8192000000 10003542496 10360651776 1014265584 11.017 100 96 186 1 1116978864 83713520 18999760
08:17:09 946 8192000000 9995284864 10362724352 1031042208 11.178 100 91 173 0 1115917040 67866224 17008608
08:18:12 898 8192000000 9997202704 10371665920 1043955568 11.303 100 96 175 1 1120355376 59175344 17224464
08:19:15 969 8192000000 10041554896 10419073024 1057390032 11.431 100 97 182 1 1164479744 88351392 18738320
08:20:18 898 8192000000 10028638080 10411175936 1062653088 11.482 100 95 181 0 1151144656 70156528 18335040
08:21:22 1992 8192000000 10030660032 10422063104 1069821888 11.550 100 76 146 2 1153041056 69111792 14107376
08:22:25 1104 8192000000 10053072000 10439221248 1076916096 11.618 100 88 171 0 1173173408 79048320 17208992
08:23:29 1058 8192000000 10031005024 10424188928 1078933264 11.637 100 86 167 5 1155672944 59089504 17650176
08:24:31 1106 8192000000 10067195520 10457284608 1086907152 11.713 100 99 185 5 1188263312 82656496 18699664
08:25:34 1109 8192000000 10051314464 10439700480 1087832752 11.722 100 94 181 0 1173729424 67685040 18211632
08:26:35 1056 8192000000 10054978864 10446512128 1088961104 11.733 100 91 172 2 1178464416 70104128 19399184
08:27:37 6149 8192000000 10035636976 10434633728 1096412992 11.804 100 60 114 0 1155095968 47492032 11190944
08:28:44 3888 8192000000 10046039600 10433867776 1079419312 11.642 98 73 141 0 1156616256 63749408 13447536
08:29:49 2810 8192000000 10033401888 10421317632 1077676896 11.625 99 74 142 1 1149148464 58007312 13464256
08:30:53 3090 8192000000 10029960576 10424639488 1078106400 11.629 100 63 118 2 1151422480 60982368 12333712
08:31:57 1534 8192000000 10019564960 10407784448 1067321872 11.526 100 80 147 5 1145268544 63530144 14416528
08:32:59 968 8192000000 10041487376 10430156800 1071373728 11.565 100 89 166 1 1163960512 75308672 17278112
08:34:02 1108 8192000000 10008332944 10386935808 1048727408 11.348 100 89 169 1 1130256368 64302112 17226848
08:35:04 1677 8192000000 10008958560 10390859776 1043634960 11.300 100 77 142 4 1130639776 72895424 14109392
08:36:07 1066 8192000000 9969473760 10342600704 1025504784 11.125 100 92 165 5 1093061088 48868480 18687824
08:37:08 15092 8192000000 9968897904 10349035520 1036301872 11.229 99 67 116 2 1102631616 55121184 11208560
08:38:32 7496 8192000000 9999167696 10383007744 1056916784 11.427 100 66 128 0 1124244480 53879296 13448400
08:39:47 1904 8192000000 10040901072 10422534144 1069088256 11.543 100 91 170 2 1163305744 74616864 19600624
08:40:53 12937 8192000000 10047416384 10442084352 1073816656 11.589 100 65 126 1 1173007152 86327232 12863264
08:42:18 12586 8192000000 10051133072 10445590528 1084052608 11.686 100 61 121 0 1169943840 73526624 12364608
08:43:38 5263 8192000000 10103173232 10495983616 1104514560 11.880 100 78 153 1 1224551728 102539920 17497248
08:44:47 5282 8192000000 10169594432 10563493888 1117709984 12.005 100 77 149 1 1278996816 144923376 16363456
08:45:58 1207 8192000000 10217309792 10609242112 1130530944 12.126 100 90 162 3 1327970672 180548720 16891008
08:47:04 4298 8192000000 10230545168 10643013632 1137481024 12.192 100 77 154 0 1348854080 194623280 16749776
08:48:13 1141 8192000000 10247643504 10648039424 1147265856 12.284 100 88 167 2 1356813424 192280448 17267120
08:49:19 956 8192000000 10284036304 10693287936 1163766320 12.439 100 95 180 1 1390093312 207981312 18345680
08:50:22 1610 8192000000 10334609168 10743042048 1170523312 12.502 100 93 186 2 1442911952 252466160 19922480
08:51:29 997 8192000000 10321421760 10720890880 1177508256 12.567 100 95 186 1 1428768928 230159568 21101104
08:52:32 960 8192000000 10364018912 10767056896 1180107120 12.591 100 97 184 0 1475803984 274375952 21320912
08:53:38 722 8192000000 10390933968 10793635840 1192234640 12.704 100 96 176 4 1498445456 284822208 21388608
08:54:41 903 8192000000 10453703616 10859483136 1199366256 12.770 100 96 182 1 1561306864 341400112 20540496
08:55:46 1867 8192000000 10489992496 10905399296 1198400656 12.761 100 94 180 0 1595978720 375920880 21657184
08:56:51 1834 8192000000 10508869056 10942152704 1204174928 12.815 100 95 185 0 1618426288 392398512 21852848
08:57:55 972 8192000000 10523697728 10939813888 1198956176 12.767 100 96 189 1 1624825056 403757040 22111840
08:58:59 959 8192000000 10547020144 10966544384 1196474976 12.744 100 95 185 0 1655214176 437036560 21702640
09:00:03 951 8192000000 10568567120 10986311680 1184331424 12.631 100 95 188 1 1674302384 466631792 23339168
09:01:08 809 8192000000 10605626656 11036946432 1191323152 12.696 100 93 183 0 1709241648 495666800 22251696
09:02:13 5445 8192000000 10587869200 11040346112 1196036064 12.740 100 66 133 0 1695703488 482470320 17197104
09:03:24 1325 8192000000 10611608800 11048865792 1177150864 12.564 100 99 193 0 1710750016 510559376 23039776
09:04:27 6555 8192000000 10601860864 11075133440 1162498960 12.427 98 69 135 0 1690424560 510761424 17164176
09:05:36 4943 8192000000 10564772752 11061166080 1186895856 12.654 100 63 120 0 1675845088 473774752 15174480
09:06:54 5149 8192000000 10602900976 11091673088 1191798560 12.700 100 48 96 0 1711063744 506929392 12335792
09:08:12 9851 8192000000 10575240592 11039997952 1181997136 12.609 100 56 110 1 1687435200 491168144 14269920
09:09:28 7643 8192000000 10523812272 11047526400 1168280240 12.481 100 48 96 0 1651523616 469927920 13315456
09:10:38 31161 8192000000 10400072016 10842501120 1150152560 12.311 99 30 55 1 1496761040 338631312 7977168
09:12:21 7871 8192000000 10377603888 10878128128 1136518256 12.183 100 36 72 0 1485867328 339968656 9380416
09:13:49 23141 8192000000 10163375312 10692407296 1133385824 12.153 100 24 47 0 1334429952 195160480 5883648
09:15:29 56219 8192000000 10045696048 10479005696 1096474800 11.804 97 19 33 0 1147021152 47377952 3168400
09:17:31 64528 8192000000 10065749376 10542899200 1132207872 12.142 100 20 34 0 1175539168 40418848 2912448
09:19:43 6824 8192000000 10091830592 10511269888 1139357712 12.209 100 74 142 2 1182814800 29487616 13969472
09:20:51 90096 8192000000 10143405440 10590638080 1126852912 12.092 98 24 46 2 1209548112 77908448 4786752
09:23:28 65073 8192000000 10075828944 10516627456 1096022176 11.800 96 22 40 1 1133301312 33404256 3874880
09:25:35 54286 8192000000 10063063376 10531479552 1131552240 12.136 99 21 40 1 1165976384 30470400 3953744
09:27:32 34999 8192000000 10128377760 10570469376 1143525440 12.249 100 39 76 0 1211852832 60107744 8219648
09:29:12 6770 8192000000 10165955552 10602033152 1160229664 12.405 100 56 107 0 1260259040 88631744 11397632
09:30:23 4139 8192000000 10191347936 10633818112 1148016768 12.291 99 63 123 1 1283137488 121483168 13637552
09:31:29 61164 8192000000 10123708832 10619473920 1126876368 12.092 99 17 34 0 1244183952 112880944 4426640
09:33:52 14570 8192000000 10099010400 10584846336 1133362592 12.153 100 18 36 0 1224233488 86908960 3961936
09:35:13 95850 8192000000 10062972240 10537529344 1107677568 11.910 98 15 28 1 1160803744 50013504 3112672
09:38:37 10092 8192000000 10045335088 10482872320 1118380096 12.012 99 48 92 1 1144518576 15340608 10797872
09:39:47 72661 8192000000 10059989312 10541236224 1124857920 12.073 99 11 22 0 1182701616 55606736 2236960
09:42:01 55760 8192000000 10073773952 10545205248 1109191616 11.925 98 13 26 0 1145747168 33976448 2579104
09:43:57 51419 8192000000 10048256464 10545500160 1097019584 11.809 97 9 17 0 1155858496 57132416 1706496
09:45:49 18962 8192000000 10138588944 10575249408 1140598352 12.221 100 47 92 0 1213223920 62940560 9685008
09:47:14 28729 8192000000 10142769296 10592702464 1137583792 12.193 99 24 48 1 1237464960 94071312 5809856
09:48:56 35998 8192000000 10210956224 10663206912 1144350752 12.256 99 30 60 0 1305457664 154253344 6853568
09:50:33 33593 8192000000 10106910272 10583670784 1117605136 12.004 98 23 46 1 1227668672 105493072 4570464
09:52:09 40237 8192000000 10157342048 10612912128 1123267408 12.058 98 47 91 0 1210259184 77869776 9122000
09:54:07 29776 8192000000 10187155328 10616827904 1139720880 12.213 99 41 80 0 1253185632 104741008 8723744
09:55:52 120694 8192000000 10073676976 10526588928 1071617712 11.568 94 25 48 0 1150552656 74303968 4630976
09:58:54 74368 8192000000 10051853616 10504146944 1133139952 12.151 100 32 62 0 1187180512 47867552 6173008
10:01:13 35513 8192000000 10069057280 10513416192 1109906176 11.932 98 39 76 0 1159327856 41800144 7621536
10:02:49 50838 8192000000 10057868288 10530881536 1120024496 12.027 100 26 51 0 1190565648 64989680 5551472
10:04:42 53249 8192000000 10043314352 10528038912 1110448496 11.937 99 6 12 0 1142443008 30873168 1121344
10:08:04 67636 8192000000 10108536176 10578145280 1113672240 11.967 99 12 24 0 1179304304 63191248 2440816
10:10:12 47512 8192000000 10069399104 10542989312 1115144688 11.981 99 17 34 1 1191233008 72414624 3673696
10:12:41 42732 8192000000 10111612416 10567462912 1103898592 11.875 97 40 78 0 1193977504 81004496 9074416
10:14:28 37496 8192000000 10119939808 10553679872 1106470800 11.899 98 33 65 0 1177802000 64094384 7236816
10:16:16 33006 8192000000 10136541872 10572288000 1132040944 12.141 100 32 64 0 1230985088 92403056 6541088
10:17:59 53863 8192000000 10099797584 10589650944 1122940656 12.055 99 18 36 0 1225623248 98655984 4026608
10:19:55 31485 8192000000 10104778672 10556084224 1134498256 12.164 99 27 55 0 1201420608 61231280 5691072
10:21:32 87303 8192000000 10138011248 10599374848 1149806368 12.308 100 40 76 1 1261825136 103410528 8608240
10:23:59 78992 8192000000 10097885776 10615742464 1128686864 12.109 99 8 16 0 1209014816 78681296 1646656
10:26:20 47627 8192000000 10113140912 10558492672 1113990064 11.970 98 24 48 0 1178779408 59538512 5250832
10:28:15 31609 8192000000 10137495856 10580566016 1146920720 12.281 100 41 82 0 1241099440 85116128 9062592
10:29:50 36429 8192000000 10184525104 10614525952 1140942128 12.224 99 34 68 1 1254344976 105754304 7648544
10:31:29 49277 8192000000 10217659136 10687528960 1147046928 12.282 99 23 46 0 1307819952 154998128 5774896
10:33:19 39924 8192000000 10162880096 10676006912 1123951504 12.064 99 15 28 0 1256043232 128211744 3879984
10:35:19 33923 8192000000 10074339792 10498809856 1098487600 11.823 97 49 96 1 1139040848 30719136 9834112
10:37:03 27310 8192000000 8951009840 9136304128 101853472 1.228 10 6 12 0 121363360 18297920 1211968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-07-2022 10:38:12 to 09-07-2022 10:38:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-07-2022 10:38:29 to 09-07-2022 10:38:31.

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          221585
MON$OLDEST_ACTIVE               222756
MON$OLDEST_SNAPSHOT             222756
MON$NEXT_TRANSACTION            222756
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                       2086752
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 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 ---
:150: 
:151: mscs-test-161.budzdorov.ru    Sat Jul  9 10:37:31 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Sat Jul  9 10:37:32 2022
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:157: 
--- end of diff output ---

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

09.07.22 10:38:32. 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-07-09 10:38:34 No severe PSQL-related problems occured
09.07.22 10:38:36. End of report.