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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-07 10:37:16
DTS_END                         2022-05-07 10:37:16.5510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45430
TRN_ID                          227896
ATT_ID                          683
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: 8962.89 20590 1075547
customer order: creation 179.94 440 21593
customer order: refuse 15.11 398 1813
order to supplier: creation 46.20 1421 5544
order to supplier: removal 3.33 168368 399
invoice (draft): creation 46.99 3183 5639
invoice (draft): removal 47.08 1001 5650
invoice accept: apply 42.60 26422 5112
invoice accept: cancel 42.98 1330 5158
customer reserve: creation 3946.20 252 473544
customer reserve: removal 4295.97 103 515516
realization accept: apply 86.08 268 10329
realization accept: cancel 77.03 192 9244
payment from customer: creation 60.25 168 7230
payment from customer: removal 5.61 144 673
payment to supplier: creation 50.28 182 6034
payment to supplier: removal 3.65 109 438
service: total inventory turnovers 4.16 3775 499
service: total monetary turnovers 8.39 362 1007
service: aggregate perf. data 1.00 15461 120
service: refresh index statistics 0.04 188223 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-05-2022 10:38:27 to 07-05-2022 10:38:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7457
TEST_TIME 2 7369
TEST_TIME 3 7937
TEST_TIME 4 7660
TEST_TIME 5 7400
TEST_TIME 6 7406
TEST_TIME 7 7215
TEST_TIME 8 7253
TEST_TIME 9 7460
TEST_TIME 10 7555
TEST_TIME 11 7734
TEST_TIME 12 7805
TEST_TIME 13 7906
TEST_TIME 14 7864
TEST_TIME 15 8184
TEST_TIME 16 8051
TEST_TIME 17 8100
TEST_TIME 18 8079
TEST_TIME 19 7990
TEST_TIME 20 7850
TEST_TIME 21 7914
TEST_TIME 22 7816
TEST_TIME 23 8049
TEST_TIME 24 8109
TEST_TIME 25 8152
TEST_TIME 26 8243
TEST_TIME 27 8521
TEST_TIME 28 8891
TEST_TIME 29 9143
TEST_TIME 30 9327
TEST_TIME 31 9321
TEST_TIME 32 9934
TEST_TIME 33 10479
TEST_TIME 34 10606
TEST_TIME 35 10998
TEST_TIME 36 10876
TEST_TIME 37 11088
TEST_TIME 38 10952
TEST_TIME 39 10688
TEST_TIME 40 10607
TEST_TIME 41 10460
TEST_TIME 42 10325
TEST_TIME 43 10243
TEST_TIME 44 10169
TEST_TIME 45 10027
TEST_TIME 46 10144
TEST_TIME 47 10202
TEST_TIME 48 10081
TEST_TIME 49 10058
TEST_TIME 50 9960
TEST_TIME 51 9863
TEST_TIME 52 9882
TEST_TIME 53 9875
TEST_TIME 54 9838
TEST_TIME 55 9821
TEST_TIME 56 9764
TEST_TIME 57 9807
TEST_TIME 58 9827
TEST_TIME 59 9754
TEST_TIME 60 9793
TEST_TIME 61 9900
TEST_TIME 62 10002
TEST_TIME 63 9956
TEST_TIME 64 9894
TEST_TIME 65 9802
TEST_TIME 66 9783
TEST_TIME 67 9712
TEST_TIME 68 9640
TEST_TIME 69 9652
TEST_TIME 70 9677
TEST_TIME 71 9600
TEST_TIME 72 9553
TEST_TIME 73 9466
TEST_TIME 74 9398
TEST_TIME 75 9423
TEST_TIME 76 9482
TEST_TIME 77 9443
TEST_TIME 78 9415
TEST_TIME 79 9574
TEST_TIME 80 9527
TEST_TIME 81 9549
TEST_TIME 82 9546
TEST_TIME 83 9539
TEST_TIME 84 9591
TEST_TIME 85 9589
TEST_TIME 86 9508
TEST_TIME 87 9498
TEST_TIME 88 9449
TEST_TIME 89 9421
TEST_TIME 90 9350
TEST_TIME 91 9304
TEST_TIME 92 9279
TEST_TIME 93 9248
TEST_TIME 94 9231
TEST_TIME 95 9237
TEST_TIME 96 9207
TEST_TIME 97 9185
TEST_TIME 98 9212
TEST_TIME 99 9247
TEST_TIME 100 9250
TEST_TIME 101 9219
TEST_TIME 102 9248
TEST_TIME 103 9318
TEST_TIME 104 9383
TEST_TIME 105 9393
TEST_TIME 106 9370
TEST_TIME 107 9291
TEST_TIME 108 9278
TEST_TIME 109 9231
TEST_TIME 110 9197
TEST_TIME 111 9173
TEST_TIME 112 9138
TEST_TIME 113 9099
TEST_TIME 114 9050
TEST_TIME 115 9054
TEST_TIME 116 9033
TEST_TIME 117 9056
TEST_TIME 118 9070
TEST_TIME 119 9049
TEST_TIME 120 8981

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-05-2022 10:38:31 to 07-05-2022 10:38:34.

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 1512139 1512139 0 0.00 0 17177 14 0 0
doc_list_biud 1512139 1512139 0 0.00 0 24417 41 0 0
sp_add_invoice_to_stock 5235 5112 123 2.34 68 225869 26423 0 123
sp_cancel_adding_invoice 5158 5158 0 0.00 7 102444 1331 0 0
sp_cancel_client_order 1813 1813 0 0.00 6 8049 399 0 0
sp_cancel_customer_reserve 515516 515516 0 0.00 1 24441 103 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 4 10282 144 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 4 1051 110 0 0
sp_cancel_supplier_invoice 5659 5650 9 0.15 3 13610 1001 0 9
sp_cancel_supplier_order 399 399 0 0.00 22 1832188 168369 0 0
sp_cancel_write_off 9244 9244 0 0.00 2 9012 193 0 0
sp_client_order 21593 21593 0 0.00 6 28981 440 0 0
sp_customer_reserve 473544 473544 0 0.00 1 201747 252 0 0
sp_fill_shopping_cart 32769 32769 0 0.00 2 17717 84 0 0
sp_fill_shopping_cart_clo_res 473527 473527 0 0.00 0 17694 19 0 0
sp_get_clo_for_invoice 5073 5073 0 0.00 0 12392 260 0 0
sp_kill_cost_storno 15513 15513 0 0.00 0 6873 75 0 0
sp_kill_qstorno_ret_qs2qd 1046325 1046325 0 0.00 0 24414 44 0 0
sp_kill_qty_storno 553182 553182 0 0.00 0 24417 102 0 0
sp_lock_selected_doc 21255 21255 0 0.00 0 6644 7 0 0
sp_make_cost_storno 28666 28666 0 0.00 0 17164 76 0 0
sp_multiply_rows_for_pdistr 28666 28666 0 0.00 0 7124 38 0 0
sp_multiply_rows_for_qdistr 21593 21593 0 0.00 0 10662 170 0 0
sp_pay_from_customer 7230 7230 0 0.00 6 8677 168 0 0
sp_pay_to_supplier 6034 6034 0 0.00 7 12372 182 0 0
sp_payment_common 13264 13264 0 0.00 2 6690 44 0 0
sp_qd_handle_on_cancel_clo 1813 1813 0 0.00 0 8025 329 0 0
sp_qd_handle_on_invoice_upd_sts 10231 10231 0 0.00 0 9600 522 0 0
sp_qd_handle_on_reserve_upd_sts 10329 10329 0 0.00 0 21681 60 0 0
sp_reserve_write_off 10329 10329 0 0.00 9 21713 269 0 0
sp_supplier_invoice 5639 5639 0 0.00 10 90378 3184 0 0
sp_supplier_order 5544 5544 0 0.00 8 34742 1422 0 0
srv_aggregate_perf_data 120 120 0 0.00 2270 163220 15461 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 717 222258 37436 0 0
srv_find_qd_qs_mism 1025485 1025485 0 0.00 0 15191 11 0 0
srv_make_invnt_saldo 499 499 0 0.00 49 68878 3775 0 0
srv_make_money_saldo 1007 1007 0 0.00 1 14701 363 0 0
srv_recalc_idx_stat 5 5 0 0.00 51560 365804 188224 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5112 5112 0 0.00 1 12526 996 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 1 9388 515 0 0
t$perf-norm:sp_cancel_client_order 1813 1813 0 0.00 0 7093 33 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 9229 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 2525 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 1044 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 632 35 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 69786 6941 0 0
t$perf-norm:sp_cancel_write_off 1065 1065 0 0.00 0 2310 19 0 0
t$perf-norm:sp_client_order 21593 21593 0 0.00 0 9329 39 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 803 14 0 0
t$perf-norm:sp_pay_from_customer 7230 7230 0 0.00 0 8146 20 0 0
t$perf-norm:sp_pay_to_supplier 6034 6034 0 0.00 0 9159 27 0 0
t$perf-norm:sp_reserve_write_off 10329 10329 0 0.00 0 11691 26 0 0
t$perf-norm:sp_supplier_invoice 5639 5639 0 0.00 0 11019 44 0 0
t$perf-norm:sp_supplier_order 5544 5544 0 0.00 0 11348 36 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 972 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 475 13 0 0
t$perf-norm:srv_make_invnt_saldo 499 499 0 0.00 0 4061 28 0 0
t$perf-norm:srv_make_money_saldo 1007 1007 0 0.00 0 8257 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 176 68 0 0
v_add_invoice_to_stock 10392 10146 246 2.36 0 7081 27 0 246
v_all_customers 38970 38970 0 0.00 0 121 4 0 0
v_all_suppliers 31148 31148 0 0.00 0 66 4 0 0
v_all_wares 43186 43186 0 0.00 0 115 4 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 713 15 0 0
v_cancel_client_order 3626 3626 0 0.00 0 1106 8 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 4702 13 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 1 857 10 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 814 11 0 0
v_cancel_supplier_invoice 786 768 18 2.29 0 959 26 0 18
v_cancel_supplier_order 798 798 0 0.00 0 774 11 0 0
v_cancel_write_off 2130 2130 0 0.00 0 2641 25 0 0
v_max_id_clo_ord 5544 5544 0 0.00 0 2254 5 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 7144 29 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 4963 8 0 0
v_max_non_paid_invoice 1636 1636 0 0.00 0 2489 9 0 0
v_max_non_paid_realizn 3849 3849 0 0.00 0 3820 8 0 0
v_min_id_clo_ord 5544 5544 0 0.00 0 1762 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 3560 16 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 6406 15 0 0
v_min_non_paid_invoice 6034 6034 0 0.00 0 5537 17 0 0
v_min_non_paid_realizn 7230 7230 0 0.00 0 5052 12 0 0
v_our_firm 10978 10978 0 0.00 0 64 5 0 0
v_reserve_write_off 20658 20658 0 0.00 0 5524 9 0 0
x_lock_depdocs_on_canc_invoice 10424 10424 0 0.00 0 23710 139 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 3277 341 0 0
x_make_qstorno_1000_1200 5544 5544 0 0.00 3 34734 1248 0 0
x_make_qstorno_1200_2000 5639 5639 0 0.00 5 89525 2954 0 0
x_make_qstorno_2100_3300 473544 473544 0 0.00 0 201742 220 0 0

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

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 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 23023 8192000000 9717389088 10010411008 789934000 8.794 100 90 180 1 819409344 7847712 21627632
08:09:48 13122 8192000000 9745125152 10048155648 822299872 9.122 100 85 170 0 854103088 11715296 20087920
08:11:04 27364 8192000000 9787665984 10104184832 855581392 9.456 100 85 170 2 892882944 17950272 19351280
08:12:35 9098 8192000000 9843861760 10174042112 902981904 9.928 100 73 146 0 958953232 39646000 16325328
08:13:50 9729 8192000000 9908310896 10248757248 945014944 10.342 100 83 165 0 1021300384 59006800 17278640
08:15:04 1980 8192000000 9950999232 10301177856 985002400 10.733 100 90 179 0 1062486208 59612400 17871408
08:16:08 1117 8192000000 9996695984 10357506048 1015322592 11.027 100 92 178 1 1116613776 83700608 17590576
08:17:10 1081 8192000000 9992693248 10357538816 1038841568 11.254 100 87 167 1 1110997712 55349744 16806400
08:18:13 1185 8192000000 10026330400 10404085760 1058260896 11.440 100 97 184 0 1151191952 73288016 19643040
08:19:16 1045 8192000000 10053370304 10431315968 1070742768 11.559 100 94 182 0 1175370240 85842576 18784896
08:20:19 1062 8192000000 10044775056 10430119936 1069341616 11.546 100 84 164 0 1168586752 82491552 16753584
08:21:21 3436 8192000000 10024762480 10423521280 1075706256 11.607 100 67 134 0 1147766864 59368192 12692416
08:22:31 6727 8192000000 10032384336 10413441024 1082894816 11.675 100 88 166 0 1153705184 53420016 17390352
08:23:39 1192 8192000000 10051623280 10439446528 1092350192 11.765 100 91 172 3 1176857072 65529360 18977520
08:24:41 1476 8192000000 10059396912 10443661312 1094415744 11.785 100 90 170 1 1180518048 68261040 17841264
08:25:43 1728 8192000000 10065588928 10449006592 1096025104 11.800 100 89 174 1 1187787984 75277072 16485808
08:26:45 1278 8192000000 10063382880 10449104896 1087449072 11.718 100 89 175 0 1182618752 77425968 17743712
08:27:48 2524 8192000000 10024355392 10421051392 1079674000 11.644 99 75 144 1 1147562096 51983280 15904816
08:28:51 1220 8192000000 10054824064 10440794112 1081069648 11.658 99 91 169 0 1176252720 76455792 18727280
08:29:54 1314 8192000000 10014237680 10391642112 1052369072 11.383 99 94 176 1 1137416256 66407648 18639536
08:30:56 998 8192000000 10008529376 10394087424 1067456752 11.528 100 82 158 2 1130925056 46445504 17022800
08:31:58 1278 8192000000 10022162208 10395336704 1046340640 11.326 98 94 175 1 1145065568 80429360 18295568
08:33:00 1364 8192000000 10001899456 10373193728 1032535664 11.193 99 92 181 3 1124788992 72965664 19287664
08:34:02 1523 8192000000 9998370720 10374144000 1043586240 11.299 100 88 168 1 1118323840 57898944 16838656
08:35:04 867 8192000000 10007658368 10388443136 1043405216 11.297 100 81 158 0 1129294944 69140336 16749392
08:36:06 1116 8192000000 10000912000 10377580544 1046522080 11.327 100 89 168 2 1124225376 59311280 18392016
08:37:08 1130 8192000000 9997561040 10374762496 1062338512 11.479 99 93 176 2 1122248880 42193152 17717216
08:38:16 10536 8192000000 10023533472 10412220416 1082740864 11.674 100 59 112 1 1148069216 54003712 11324640
08:39:35 1668 8192000000 10080264768 10463625216 1101449200 11.851 100 89 166 1 1199618672 81343392 16826080
08:40:39 2374 8192000000 10068739760 10461601792 1114862832 11.978 100 64 125 2 1190498992 62975008 12661152
08:41:45 2216 8192000000 10120835200 10518896640 1125891744 12.083 100 82 155 1 1239583792 97587776 16104272
08:42:52 2795 8192000000 10120240768 10519863296 1135867136 12.177 100 65 124 0 1235383328 86569488 12946704
08:44:01 1122 8192000000 10127235504 10527252480 1143251824 12.246 100 81 151 0 1243681376 84733264 15696288
08:45:07 1250 8192000000 10167311648 10565124096 1151419920 12.323 100 94 176 0 1283028320 112427232 19181168
08:46:11 737 8192000000 10214292688 10613698560 1163488880 12.436 100 93 184 0 1329903632 146557264 19857488
08:47:14 1665 8192000000 10224950704 10630254592 1166307936 12.462 100 83 158 2 1335843008 152021952 17513120
08:48:19 1111 8192000000 10254305200 10671185920 1165315696 12.453 100 93 181 0 1365763168 178727360 21720112
08:49:24 2086 8192000000 10234235424 10649137152 1160330000 12.406 100 68 128 1 1347894336 172260384 15303952
08:50:29 937 8192000000 10271260000 10678976512 1165298160 12.453 100 95 177 2 1381138720 194833472 21007088
08:51:34 1463 8192000000 10296490400 10708680704 1176558096 12.558 100 91 176 1 1408069920 210450928 21060896
08:52:38 1794 8192000000 10299127248 10719563776 1194086992 12.721 100 87 169 1 1409063024 194101280 20874752
08:53:43 942 8192000000 10324812496 10740162560 1200213664 12.778 100 96 188 3 1428703568 206865376 21624528
08:54:48 708 8192000000 10342950848 10749526016 1192356128 12.705 100 94 176 1 1449710656 237269968 20084560
08:55:53 3222 8192000000 10389516288 10798567424 1202437696 12.799 99 82 156 1 1496846928 276672688 17736544
08:56:59 1348 8192000000 10443460944 10855972864 1211172048 12.880 100 88 174 1 1549004448 317570608 20261792
08:58:05 808 8192000000 10498575888 10916007936 1214773728 12.913 100 98 194 0 1604467840 365468080 24226032
08:59:09 1234 8192000000 10518996832 10921525248 1190995424 12.693 100 96 189 2 1618787248 405418192 22373632
09:00:14 911 8192000000 10570438960 11004547072 1210602368 12.875 100 99 191 1 1673817264 439465584 23749312
09:01:17 2845 8192000000 10551069392 10984685568 1200253984 12.779 100 67 130 1 1656493504 439604368 16635152
09:02:28 727 8192000000 10584286608 11009421312 1191958576 12.702 100 88 171 0 1685802880 473454704 20389600
09:03:32 1299 8192000000 10556129264 10987851776 1179071136 12.582 100 91 178 1 1661041776 459785008 22185632
09:04:36 2961 8192000000 10552412832 11027857408 1172362240 12.519 100 75 145 0 1656288720 465981888 17944592
09:05:44 9632 8192000000 10488552048 10979577856 1169079248 12.488 100 54 103 1 1626815072 444683456 13052368
09:07:02 11158 8192000000 10460176016 10952617984 1178764384 12.579 100 43 83 1 1570050624 380798432 10487808
09:08:25 13793 8192000000 10391201392 10890940416 1150764016 12.317 99 50 99 1 1488637136 325835664 12037456
09:09:40 18944 8192000000 10291885552 10770149376 1141812848 12.233 100 36 67 1 1408861216 259153936 7894432
09:11:07 47067 8192000000 10180416080 10676269056 1145275408 12.265 100 14 28 0 1327152208 178827472 3049328
09:12:56 70373 8192000000 10133023136 10628481024 1145513152 12.267 100 23 44 1 1262192832 112018160 4661520
09:15:15 109757 8192000000 10088287248 10527457280 1119948464 12.027 98 29 56 0 1150245808 24520592 5776752
09:18:09 79100 8192000000 10073552784 10521161728 1122702160 12.053 98 31 62 0 1158153552 28136272 7315120
09:20:29 28637 8192000000 10128241040 10579427328 1135531120 12.173 99 44 86 1 1217865424 73186000 9148304
09:23:03 51079 8192000000 10140344624 10621526016 1123055888 12.056 99 33 65 1 1247752992 117286080 7411024
09:24:56 222239 8192000000 10084020176 10540072960 1087375840 11.718 95 20 38 1 1149908048 58580624 3951584
09:29:49 51733 8192000000 10059893984 10538213376 1102653552 11.863 97 12 24 0 1136979520 31727024 2598944
09:31:44 23326 8192000000 10079710512 10525908992 1132997120 12.150 100 27 54 3 1173387536 35029408 5361008
09:33:09 42352 8192000000 10161788256 10599284736 1134754816 12.166 99 46 92 1 1228257600 83706560 9796224
09:34:56 36276 8192000000 10136160144 10588794880 1112211616 11.953 98 21 41 1 1213331600 96414416 4705568
09:37:34 77661 8192000000 10100858864 10559164416 1126088640 12.084 99 17 34 0 1222461808 92770224 3602944
09:39:55 60733 8192000000 10105064016 10540584960 1116860992 11.997 97 20 40 0 1157500224 36789840 3849392
09:41:59 88632 8192000000 10069020624 10551193600 1128477264 12.107 99 32 62 1 1200543376 65443552 6622560
09:44:28 43245 8192000000 10116687568 10566082560 1126644864 12.090 98 29 58 0 1208713728 76597952 5470912
09:46:12 55808 8192000000 10073426784 10529873920 1102207712 11.859 97 18 37 1 1172440832 66179344 4053776
09:48:14 60439 8192000000 10059721312 10506125312 1101878384 11.855 97 21 42 0 1143308544 36202144 5228016
09:50:23 78311 8192000000 10130449232 10597466112 1126293808 12.086 99 12 23 1 1192405936 63689632 2422496
09:52:42 76621 8192000000 10116639552 10620338176 1116815120 11.997 98 19 38 0 1214085776 93151088 4119568
09:55:00 64711 8192000000 10170386096 10622418944 1109685264 11.929 97 23 46 0 1204552624 89692400 5174960
09:57:11 18900 8192000000 10081049520 10511466496 1120436112 12.031 99 37 70 0 1165550608 37693600 7420896
09:58:32 45642 8192000000 10101045104 10538004480 1127020544 12.093 99 43 86 0 1195984160 60477808 8485808
10:00:19 47824 8192000000 10139773440 10581147648 1130012896 12.121 100 46 90 0 1250041952 110381632 9647424
10:02:11 25288 8192000000 10079208144 10509590528 1125650288 12.080 99 48 87 4 1162144704 26794176 9700240
10:03:37 77637 8192000000 10074274400 10540220416 1115525792 11.985 99 44 86 0 1200808672 76260128 9022752
10:06:11 84595 8192000000 10057063408 10539470848 1096528528 11.805 97 10 20 0 1135568544 36870560 2169456
10:08:35 40372 8192000000 10042219408 10493980672 1117723152 12.005 99 8 16 0 1152205792 32602192 1880448
10:10:16 40471 8192000000 10087923216 10541305856 1121729984 12.043 98 17 34 0 1161338960 35765648 3843328
10:11:58 30340 8192000000 10121815184 10567049216 1143241920 12.246 100 36 71 2 1215363696 64921600 7200176
10:13:32 35522 8192000000 10153755248 10592763904 1146175392 12.274 99 21 42 0 1231869648 81192160 4502096
10:15:10 65025 8192000000 10126230624 10637836288 1136910736 12.186 98 26 53 2 1253404384 110266384 6227264
10:17:18 42434 8192000000 10157362464 10596081664 1100008832 11.838 95 42 82 1 1201199104 92640304 8549968
10:19:07 52447 8192000000 10218213728 10703872000 1140144240 12.217 99 37 74 0 1311125456 162761216 8220000
10:20:59 74334 8192000000 10110226944 10552201216 1068037024 11.533 94 20 40 0 1145174720 72995488 4142208
10:23:14 23295 8192000000 10084043840 10529857536 1107603824 11.910 98 46 92 1 1157152336 40234032 9314480
10:24:37 92485 8192000000 10049440848 10517172224 1111784368 11.949 99 14 28 0 1156548096 42180368 2583360
10:27:30 91880 8192000000 10060549104 10560012288 1136778720 12.185 100 5 10 0 1195459664 57656464 1024480
10:30:31 116567 8192000000 10095603728 10552147968 1111268528 11.944 97 15 27 1 1161557712 47172896 3116288
10:33:28 58908 8192000000 10043472448 10498207744 1081477296 11.662 96 22 44 0 1147193744 60490544 5225904
10:35:34 48445 8192000000 10058825328 10500366336 1072938752 11.580 96 25 50 0 1120340736 42133472 5268512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 07-05-2022 10:38:41 to 07-05-2022 10:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-05-2022 10:38:58 to 07-05-2022 10:39:00.

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          226000
MON$OLDEST_ACTIVE               228017
MON$OLDEST_SNAPSHOT             228017
MON$NEXT_TRANSACTION            228017
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                       2091264
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
:51: 
:52: mscs-test-161.budzdorov.ru    Sat May  7 10:37:17 2022
:53:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:54: 
:55: 
:56: mscs-test-161.budzdorov.ru    Sat May  7 10:37:18 2022
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Sat May  7 10:37:21 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Sat May  7 10:37:27 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
--- end of diff output ---

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

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