Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.05.2022 10:40
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 16081500 kB
MemAvailable 31401672 kB
Buffers 0 kB
Cached 15653948 kB
SwapCached 2436 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-29 10:37:14
DTS_END                         2022-05-29 10:37:13.9290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43066
TRN_ID                          224682
ATT_ID                          618
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: 9158.20 24707 1098984
customer order: creation 176.30 428 21156
customer order: refuse 14.62 362 1754
order to supplier: creation 46.81 1483 5617
order to supplier: removal 3.49 154576 419
invoice (draft): creation 45.93 3252 5511
invoice (draft): removal 50.58 944 6070
invoice accept: apply 42.12 26262 5054
invoice accept: cancel 45.82 1189 5498
customer reserve: creation 3946.98 249 473638
customer reserve: removal 4476.30 95 537156
realization accept: apply 85.22 285 10226
realization accept: cancel 90.16 150 10819
payment from customer: creation 61.90 169 7428
payment from customer: removal 5.89 127 707
payment to supplier: creation 50.20 167 6024
payment to supplier: removal 3.43 104 412
service: total inventory turnovers 3.61 4074 433
service: total monetary turnovers 7.86 340 943
service: aggregate perf. data 0.96 14924 115
service: refresh index statistics 0.03 284951 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-05-2022 10:40:31 to 29-05-2022 10:40:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5969
TEST_TIME 2 6364
TEST_TIME 3 5536
TEST_TIME 4 5711
TEST_TIME 5 5742
TEST_TIME 6 6411
TEST_TIME 7 6553
TEST_TIME 8 6395
TEST_TIME 9 6519
TEST_TIME 10 6543
TEST_TIME 11 6644
TEST_TIME 12 6965
TEST_TIME 13 7047
TEST_TIME 14 7272
TEST_TIME 15 7543
TEST_TIME 16 7614
TEST_TIME 17 7913
TEST_TIME 18 8164
TEST_TIME 19 8024
TEST_TIME 20 8211
TEST_TIME 21 8081
TEST_TIME 22 8090
TEST_TIME 23 8317
TEST_TIME 24 8267
TEST_TIME 25 8537
TEST_TIME 26 8705
TEST_TIME 27 8605
TEST_TIME 28 9040
TEST_TIME 29 9271
TEST_TIME 30 9451
TEST_TIME 31 9810
TEST_TIME 32 9842
TEST_TIME 33 10718
TEST_TIME 34 10635
TEST_TIME 35 10947
TEST_TIME 36 11210
TEST_TIME 37 11295
TEST_TIME 38 11243
TEST_TIME 39 11094
TEST_TIME 40 10964
TEST_TIME 41 10924
TEST_TIME 42 10945
TEST_TIME 43 10860
TEST_TIME 44 10766
TEST_TIME 45 10741
TEST_TIME 46 10559
TEST_TIME 47 10414
TEST_TIME 48 10309
TEST_TIME 49 10290
TEST_TIME 50 10215
TEST_TIME 51 10241
TEST_TIME 52 10130
TEST_TIME 53 10115
TEST_TIME 54 10057
TEST_TIME 55 9994
TEST_TIME 56 9982
TEST_TIME 57 9957
TEST_TIME 58 9911
TEST_TIME 59 10003
TEST_TIME 60 9936
TEST_TIME 61 9843
TEST_TIME 62 9725
TEST_TIME 63 9753
TEST_TIME 64 9855
TEST_TIME 65 9810
TEST_TIME 66 9759
TEST_TIME 67 9809
TEST_TIME 68 9836
TEST_TIME 69 9847
TEST_TIME 70 9876
TEST_TIME 71 9829
TEST_TIME 72 9789
TEST_TIME 73 9778
TEST_TIME 74 9818
TEST_TIME 75 9963
TEST_TIME 76 9987
TEST_TIME 77 9916
TEST_TIME 78 9855
TEST_TIME 79 9801
TEST_TIME 80 9738
TEST_TIME 81 9719
TEST_TIME 82 9730
TEST_TIME 83 9660
TEST_TIME 84 9614
TEST_TIME 85 9593
TEST_TIME 86 9514
TEST_TIME 87 9519
TEST_TIME 88 9522
TEST_TIME 89 9486
TEST_TIME 90 9544
TEST_TIME 91 9581
TEST_TIME 92 9583
TEST_TIME 93 9608
TEST_TIME 94 9590
TEST_TIME 95 9562
TEST_TIME 96 9599
TEST_TIME 97 9602
TEST_TIME 98 9602
TEST_TIME 99 9625
TEST_TIME 100 9540
TEST_TIME 101 9510
TEST_TIME 102 9442
TEST_TIME 103 9380
TEST_TIME 104 9318
TEST_TIME 105 9266
TEST_TIME 106 9228
TEST_TIME 107 9318
TEST_TIME 108 9313
TEST_TIME 109 9291
TEST_TIME 110 9288
TEST_TIME 111 9305
TEST_TIME 112 9323
TEST_TIME 113 9334
TEST_TIME 114 9314
TEST_TIME 115 9293
TEST_TIME 116 9350
TEST_TIME 117 9326
TEST_TIME 118 9342
TEST_TIME 119 9268
TEST_TIME 120 9192

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-05-2022 10:40:35 to 29-05-2022 10:40:38.

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 1533677 1533677 0 0.00 0 18477 14 0 0
doc_list_biud 1533663 1533663 0 0.00 0 31736 39 0 0
sp_add_invoice_to_stock 5194 5054 140 2.69 59 223582 26262 0 140
sp_cancel_adding_invoice 5498 5498 0 0.00 6 78785 1190 0 0
sp_cancel_client_order 1754 1754 0 0.00 6 11198 362 0 0
sp_cancel_customer_reserve 537156 537156 0 0.00 1 19295 95 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 4 12868 128 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 6267 105 0 0
sp_cancel_supplier_invoice 6079 6070 9 0.14 3 23572 945 0 9
sp_cancel_supplier_order 419 419 0 0.00 15 1343109 154576 0 0
sp_cancel_write_off 10819 10819 0 0.00 2 10046 151 0 0
sp_client_order 21156 21156 0 0.00 5 31979 429 0 0
sp_customer_reserve 473638 473638 0 0.00 1 53915 250 0 0
sp_fill_shopping_cart 32278 32278 0 0.00 2 36494 81 0 0
sp_fill_shopping_cart_clo_res 473608 473608 0 0.00 0 29706 17 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 36807 280 0 0
sp_kill_cost_storno 17436 17436 0 0.00 0 13748 68 0 0
sp_kill_qstorno_ret_qs2qd 1091620 1091620 0 0.00 0 23565 41 0 0
sp_kill_qty_storno 576948 576948 0 0.00 0 31735 95 0 0
sp_lock_selected_doc 21052 21052 0 0.00 0 12408 8 0 0
sp_make_cost_storno 28686 28686 0 0.00 0 15425 81 0 0
sp_multiply_rows_for_pdistr 28684 28684 0 0.00 0 8508 37 0 0
sp_multiply_rows_for_qdistr 21156 21156 0 0.00 0 12032 158 0 0
sp_pay_from_customer 7428 7428 0 0.00 6 18003 169 0 0
sp_pay_to_supplier 6024 6024 0 0.00 6 17841 167 0 0
sp_payment_common 13451 13451 0 0.00 2 12055 43 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 1 11186 292 0 0
sp_qd_handle_on_invoice_upd_sts 10504 10504 0 0.00 0 13923 471 0 0
sp_qd_handle_on_reserve_upd_sts 10226 10226 0 0.00 0 31734 66 0 0
sp_reserve_write_off 10226 10226 0 0.00 8 31919 286 0 0
sp_supplier_invoice 5511 5511 0 0.00 10 84898 3253 0 0
sp_supplier_order 5617 5617 0 0.00 7 40440 1484 0 0
srv_aggregate_perf_data 115 115 0 0.00 2657 196107 14924 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 834 130554 34621 0 0
srv_find_qd_qs_mism 1048035 1048035 0 0.00 0 18463 11 0 0
srv_make_invnt_saldo 433 433 0 0.00 55 82557 4075 0 0
srv_make_money_saldo 943 943 0 0.00 1 13830 340 0 0
srv_recalc_idx_stat 4 4 0 0.00 121522 497560 284951 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 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 5060 5060 0 0.00 1 17152 850 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 1 6332 375 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 4403 29 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 4284 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1963 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1210 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 1706 35 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 97600 6559 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 3995 21 0 0
t$perf-norm:sp_client_order 21157 21157 0 0.00 0 9708 32 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 592 13 0 0
t$perf-norm:sp_pay_from_customer 7429 7429 0 0.00 0 4024 17 0 0
t$perf-norm:sp_pay_to_supplier 6025 6025 0 0.00 0 4236 18 0 0
t$perf-norm:sp_reserve_write_off 10226 10226 0 0.00 0 5155 20 0 0
t$perf-norm:sp_supplier_invoice 5512 5512 0 0.00 0 3292 25 0 0
t$perf-norm:sp_supplier_order 5617 5617 0 0.00 0 10504 32 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1356 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 69 4 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 2271 21 0 0
t$perf-norm:srv_make_money_saldo 943 943 0 0.00 0 2163 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 175 54 0 0
v_add_invoice_to_stock 10280 10000 280 2.72 0 6224 23 0 280
v_all_customers 39084 39084 0 0.00 0 161 3 0 0
v_all_suppliers 31408 31408 0 0.00 0 162 4 0 0
v_all_wares 42306 42306 0 0.00 0 91 3 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2346 15 0 0
v_cancel_client_order 3508 3508 0 0.00 0 818 7 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 1337 9 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 627 9 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 4882 15 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 392 19 0 18
v_cancel_supplier_order 838 838 0 0.00 0 2751 18 0 0
v_cancel_write_off 2016 2016 0 0.00 0 1454 21 0 0
v_max_id_clo_ord 5616 5616 0 0.00 0 479 4 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 1810 11 0 0
v_max_id_ord_sup 5508 5508 0 0.00 0 3168 7 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 288 7 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 1500 7 0 0
v_min_id_clo_ord 5616 5616 0 0.00 0 7221 9 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 4970 19 0 0
v_min_id_ord_sup 5508 5508 0 0.00 0 8550 12 0 0
v_min_non_paid_invoice 6024 6024 0 0.00 0 6095 13 0 0
v_min_non_paid_realizn 7427 7427 0 0.00 0 12038 19 0 0
v_our_firm 10716 10716 0 0.00 0 161 4 0 0
v_reserve_write_off 20450 20450 0 0.00 0 4304 10 0 0
x_lock_depdocs_on_canc_invoice 11143 11143 0 0.00 0 13513 128 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 3073 305 0 0
x_make_qstorno_1000_1200 5617 5617 0 0.00 2 40430 1318 0 0
x_make_qstorno_1200_2000 5511 5511 0 0.00 5 84839 3026 0 0
x_make_qstorno_2100_3300 473635 473635 0 0.00 0 53913 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-05-2022 10:40:39 to 29-05-2022 10:40:43.

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:17 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:20 20474 8192000000 9742461552 10046148608 824293360 9.142 100 90 181 0 853605808 7915408 21397040
08:09:46 21541 8192000000 9783164960 10093965312 851122480 9.411 100 91 182 1 886176976 13760496 21294000
08:11:12 21845 8192000000 9812306864 10134003712 875678288 9.657 100 85 170 0 915799264 20185248 19935728
08:12:42 7961 8192000000 9868957872 10200289280 916423920 10.061 100 89 178 0 978363440 42789184 19150336
08:13:52 4343 8192000000 9932160640 10270543872 942517728 10.318 100 90 178 0 1038416080 76809984 19088368
08:14:59 2050 8192000000 9966431264 10322763776 983052992 10.714 100 91 177 0 1085710496 84940432 17717072
08:16:06 1347 8192000000 10007880032 10371121152 1011694768 10.992 100 94 183 1 1125491504 95640288 18156448
08:17:10 1099 8192000000 9998945248 10370469888 1033119888 11.198 100 91 177 0 1121843040 70804560 17918592
08:18:13 1159 8192000000 10015097680 10386739200 1054191472 11.401 100 98 196 2 1135846416 62177072 19477872
08:19:15 2200 8192000000 10037121232 10416324608 1063023600 11.485 100 88 173 2 1157659680 77574576 17061504
08:20:19 995 8192000000 10041229600 10422288384 1069331840 11.546 100 94 180 2 1166723712 78803072 18588800
08:21:20 1140 8192000000 10044072512 10432159744 1073817072 11.589 100 94 180 1 1167494864 74892624 18785168
08:22:22 1013 8192000000 10054509936 10442309632 1082790384 11.674 100 93 175 2 1179856592 78017904 19048304
08:23:24 1071 8192000000 10046537776 10435534848 1085986368 11.704 100 91 174 2 1172704832 68585792 18132672
08:24:26 1335 8192000000 10061185264 10451984384 1088991936 11.733 100 98 187 4 1180775216 72997616 18785664
08:25:29 1272 8192000000 10066223952 10454519808 1094891552 11.789 100 96 185 2 1189440832 75446256 19103024
08:26:31 1670 8192000000 10062893648 10458263552 1095237616 11.792 100 83 163 0 1185732000 75498032 14996352
08:27:34 1974 8192000000 10029682416 10420568064 1096517040 11.805 100 69 134 0 1150743648 39879136 14347472
08:28:36 1597 8192000000 10046664624 10440966144 1099168000 11.830 100 85 159 2 1167051568 50572960 17310608
08:29:38 994 8192000000 10053136496 10443694080 1093129872 11.772 100 93 178 2 1171306672 59196624 18980176
08:30:40 1148 8192000000 10031120592 10414530560 1072348688 11.575 99 96 187 0 1155807104 62969744 20488672
08:31:43 1014 8192000000 10017867104 10396299264 1056554880 11.423 99 94 180 0 1138002560 64196768 17250912
08:32:45 3014 8192000000 9972073328 10350571520 1034986768 11.216 99 74 144 1 1096368080 47043328 14337984
08:33:48 7334 8192000000 9986755216 10362089472 1039725552 11.262 100 71 135 1 1110890256 57086224 14078480
08:34:56 1682 8192000000 9972844192 10349182976 1035961104 11.226 100 89 160 5 1094420720 41859424 16600192
08:36:00 14337 8192000000 9990238800 10378375168 1030468816 11.173 99 57 112 1 1099287424 58368000 10450608
08:37:19 4027 8192000000 10015264240 10393083904 1060182512 11.458 100 87 167 1 1136872432 62076256 14613664
08:38:37 11529 8192000000 10021682064 10404974592 1056435968 11.422 99 66 124 1 1129554448 60900752 12217728
08:39:53 987 8192000000 10037591728 10423590912 1076678240 11.616 100 80 146 4 1156176448 63414080 16084128
08:41:01 3084 8192000000 10066317808 10456612864 1091911344 11.761 100 67 129 1 1183068672 76786592 14370736
08:42:06 3714 8192000000 10100125024 10490540032 1095622960 11.796 100 75 143 0 1218279392 107232448 15423984
08:43:17 1528 8192000000 10128211440 10531614720 1115780016 11.987 100 80 154 1 1245004560 113426112 15798432
08:44:23 4745 8192000000 10138684368 10539233280 1125085344 12.075 100 60 118 1 1254772368 117180912 12506112
08:45:33 2220 8192000000 10158497616 10561302528 1134169552 12.161 100 66 128 4 1275675056 127868160 13637344
08:46:39 3714 8192000000 10200725008 10605547520 1146517472 12.277 100 83 165 0 1318667728 153675904 18474352
08:47:47 1120 8192000000 10267958112 10661085184 1153862688 12.346 100 96 180 1 1377827408 204799008 19165712
08:48:52 2335 8192000000 10281835872 10690527232 1165862352 12.458 100 90 174 0 1395363392 210215344 19285696
08:49:59 821 8192000000 10299368464 10696372224 1167530064 12.474 100 97 183 0 1411048368 221974176 21544128
08:51:03 3991 8192000000 10329843504 10747523072 1175061440 12.544 100 85 166 0 1443760784 249955760 18743584
08:52:09 885 8192000000 10360425072 10774536192 1185440768 12.641 100 95 183 0 1470716320 264150496 21125056
08:53:12 2812 8192000000 10364986608 10804396032 1188948736 12.674 100 87 171 2 1474118416 264515552 20654128
08:54:21 2218 8192000000 10380557392 10832777216 1192805744 12.709 100 98 185 2 1487130096 272210336 22114016
08:55:26 5226 8192000000 10401138208 10834804736 1195978000 12.739 100 63 125 0 1511875120 301199888 14697232
08:56:38 1143 8192000000 10411372304 10834157568 1197295360 12.751 100 90 175 2 1522670624 305556224 19819040
08:57:41 6685 8192000000 10428564048 10860302336 1190154832 12.685 100 54 107 0 1532178064 328446352 13576880
08:58:52 3652 8192000000 10462108528 10901274624 1198821120 12.765 100 56 104 0 1568500976 354834592 14845264
09:00:01 7860 8192000000 10507420016 10956722176 1189640048 12.680 99 71 140 1 1607382192 400598832 17143312
09:01:18 7882 8192000000 10521190000 10956128256 1190147760 12.685 100 64 125 0 1625053264 419233472 15672032
09:02:31 3912 8192000000 10531108736 10975965184 1195274224 12.732 100 60 120 0 1636037952 425589184 15174544
09:03:42 1723 8192000000 10532009520 10985676800 1182376528 12.612 100 60 120 0 1640355664 442405888 15573248
09:04:54 6109 8192000000 10537996672 11036020736 1190127520 12.685 100 67 133 0 1659582544 453251408 16203616
09:06:06 8339 8192000000 10549157232 10990555136 1159549008 12.399 98 47 93 1 1640328336 468663360 12115968
09:07:19 13852 8192000000 10504642976 10992238592 1171498320 12.511 100 36 72 1 1602225792 421689600 9037872
09:08:48 23737 8192000000 10428162432 10895478784 1165083632 12.451 100 32 62 1 1550326656 377377424 7865600
09:10:13 8510 8192000000 10246015200 10763415552 1149555232 12.305 99 36 71 0 1360915488 203170608 8189648
09:11:22 38542 8192000000 10255644416 10700644352 1155035376 12.357 100 28 55 0 1363081568 201675216 6370976
09:13:01 45986 8192000000 10108413584 10608451584 1131012144 12.131 99 6 12 0 1259458000 127206496 1239360
09:14:47 130553 8192000000 10077770384 10534182912 1115011136 11.980 98 10 20 0 1171436080 54634208 1790736
09:17:58 66290 8192000000 10078799840 10532274176 1078761888 11.636 96 19 39 1 1118591904 36075568 3754448
09:20:05 69023 8192000000 10056789056 10478223360 1128048224 12.103 100 49 97 3 1157760048 17485664 12226160
09:22:15 53386 8192000000 10060778800 10504445952 1085084576 11.696 95 13 27 2 1119374864 31233248 3057040
09:24:11 92098 8192000000 10079052592 10510458880 1120080720 12.028 99 40 80 2 1163634240 34794064 8759456
09:26:51 57375 8192000000 10118029232 10570928128 1145697952 12.269 100 52 96 3 1219945216 62386192 11861072
09:28:50 24967 8192000000 10045581504 10495643648 1125354320 12.078 100 24 48 0 1164341056 33714064 5272672
09:30:27 17843 8192000000 10078790656 10519064576 1132061392 12.141 100 28 56 1 1186067968 48084320 5922256
09:31:49 18019 8192000000 10104634816 10539798528 1140545264 12.221 100 27 54 0 1204906448 58522048 5839136
09:33:09 7721 8192000000 10134138640 10567405568 1144574768 12.259 100 56 103 2 1230424448 74945936 10903744
09:34:17 55553 8192000000 10124082352 10587742208 1115799232 11.987 98 16 31 1 1222933024 103400752 3733040
09:36:14 48153 8192000000 10063265216 10503962624 1109317680 11.926 98 20 40 1 1158528224 45157248 4053296
09:38:03 92508 8192000000 10146254160 10614329344 1146424256 12.276 100 18 36 1 1200598176 50572112 3601808
09:41:16 66627 8192000000 10062521808 10522521600 1092665376 11.768 96 14 26 2 1138485216 43178656 2641184
09:43:23 10711 8192000000 10106217056 10539925504 1129485504 12.117 100 49 92 1 1200055872 60725472 9844896
09:44:56 11345 8192000000 10093175872 10521210880 1104511392 11.880 98 45 90 1 1162072192 48541312 9019488
09:46:14 48134 8192000000 10067178928 10527141888 1102571600 11.862 98 34 68 1 1156226032 45903024 7751408
09:48:03 32914 8192000000 10109506752 10544906240 1111551488 11.947 98 32 63 0 1169059616 51270624 6237504
09:49:39 46299 8192000000 10170211008 10617786368 1112161440 11.953 97 29 56 1 1224779520 106581072 6037008
09:51:39 54668 8192000000 10094920560 10547929088 1074456640 11.595 96 16 32 0 1184862080 106886288 3519152
09:53:44 33005 8192000000 10062184864 10495983616 1096578736 11.805 98 32 64 0 1128696736 25986192 6131808
09:55:25 92545 8192000000 10059433072 10490155008 1040160832 11.266 93 15 30 1 1076594896 32951328 3482736
09:58:00 26272 8192000000 10089172576 10515820544 1082208448 11.669 98 52 102 0 1183534800 90693984 10632368
09:59:28 92491 8192000000 10077047920 10527207424 1111316192 11.945 100 15 29 0 1165281488 50520512 3444784
10:02:04 68205 8192000000 10113358848 10597093376 1109815536 11.931 99 14 28 0 1217718640 104329424 3573680
10:05:02 74263 8192000000 10120086176 10570493952 1093476592 11.776 97 21 42 2 1176471664 78460592 4534480
10:07:21 51262 8192000000 10110550768 10550759424 1097106512 11.810 98 21 41 1 1202250736 100561952 4582272
10:09:12 17360 8192000000 10034412016 10455756800 1071042464 11.562 95 39 67 4 1094961984 17037472 6882048
10:10:30 61151 8192000000 10080815904 10549481472 1097618656 11.815 98 51 103 0 1184151360 75987664 10545040
10:12:35 7648 8192000000 10090546064 10512297984 1120213616 12.029 100 70 136 1 1179481984 45480160 13788208
10:13:44 31871 8192000000 10112274832 10592309248 1123664464 12.062 100 43 86 1 1233947344 101845184 8437696
10:15:17 48681 8192000000 10113343040 10564243456 1106599312 11.900 98 42 84 0 1204981920 90158400 8224208
10:17:06 83310 8192000000 10064522624 10519519232 1129130256 12.113 100 28 54 2 1172473856 37298352 6045248
10:19:30 87020 8192000000 10085848192 10541813760 1114027280 11.971 98 14 27 0 1159479168 42538608 2913280
10:21:57 120117 8192000000 10156406976 10648481792 1140223408 12.218 100 16 32 0 1270277776 126418880 3635488
10:24:57 40547 8192000000 10075658704 10550517760 1136163056 12.179 100 15 30 0 1178442560 38700752 3578752
10:26:40 75957 8192000000 10120498400 10574602240 1136741472 12.185 99 28 54 2 1219002704 76540144 5721088
10:28:56 69656 8192000000 10069330864 10561867776 1113103952 11.962 99 25 50 0 1204085968 85035488 5946528
10:31:08 26931 8192000000 10099829840 10523607040 1108217936 11.916 98 52 103 3 1176840608 57871408 10751264
10:32:38 29978 8192000000 10132269824 10603298816 1129020464 12.112 100 39 77 1 1251586992 114928688 7637840
10:34:15 31933 8192000000 10095991152 10530910208 1098660800 11.825 97 43 86 1 1159298048 52336112 8301136
10:35:48 119858 8192000000 9093383760 9374932992 244777680 2.901 22 14 28 0 286702432 39117104 2807648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 29-05-2022 10:40:45 to 29-05-2022 10:41:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 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 29-05-2022 10:41:02 to 29-05-2022 10:41:04.

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          224956
MON$OLDEST_ACTIVE               224957
MON$OLDEST_SNAPSHOT             224957
MON$NEXT_TRANSACTION            224957
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                       2076384
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:155: 
:156: mscs-test-161.budzdorov.ru    Sun May 29 10:37:53 2022
:157:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:158: 
--- end of diff output ---

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

29.05.22 10:41:05. 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-29 10:41:08 No severe PSQL-related problems occured
29.05.22 10:41:09. End of report.