Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.04.2022 22:39
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 16703000 kB
MemAvailable 31608368 kB
Buffers 0 kB
Cached 15044004 kB
SwapCached 2916 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 3.0.10
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-04-25 22:38:13
DTS_END                         2022-04-25 22:38:13.9780
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(57:17)
IP                              ::1/55184
TRN_ID                          237493
ATT_ID                          701
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: 9062.62 22429 1087514
customer order: creation 163.53 391 19623
customer order: refuse 14.07 336 1688
order to supplier: creation 42.21 1316 5065
order to supplier: removal 3.42 104564 410
invoice (draft): creation 43.91 3551 5269
invoice (draft): removal 54.44 847 6533
invoice accept: apply 39.29 17645 4715
invoice accept: cancel 49.33 766 5920
customer reserve: creation 3590.29 175 430835
customer reserve: removal 4775.24 57 573029
realization accept: apply 78.54 211 9425
realization accept: cancel 87.24 121 10469
payment from customer: creation 55.64 123 6677
payment from customer: removal 5.30 83 636
payment to supplier: creation 45.18 176 5421
payment to supplier: removal 3.27 141 392
service: total inventory turnovers 4.22 1273 506
service: total monetary turnovers 6.61 23 793
service: aggregate perf. data 0.87 15295 104
service: refresh index statistics 0.03 301487 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 25-04-2022 22:39:54 to 25-04-2022 22:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2947
TEST_TIME 2 3067
TEST_TIME 3 2652
TEST_TIME 4 3002
TEST_TIME 5 3954
TEST_TIME 6 3931
TEST_TIME 7 3889
TEST_TIME 8 5099
TEST_TIME 9 5316
TEST_TIME 10 5121
TEST_TIME 11 5597
TEST_TIME 12 5604
TEST_TIME 13 5888
TEST_TIME 14 6032
TEST_TIME 15 6117
TEST_TIME 16 6301
TEST_TIME 17 6834
TEST_TIME 18 7384
TEST_TIME 19 7459
TEST_TIME 20 7619
TEST_TIME 21 7759
TEST_TIME 22 8168
TEST_TIME 23 8241
TEST_TIME 24 9026
TEST_TIME 25 8844
TEST_TIME 26 9512
TEST_TIME 27 10344
TEST_TIME 28 11029
TEST_TIME 29 10760
TEST_TIME 30 10533
TEST_TIME 31 10485
TEST_TIME 32 10530
TEST_TIME 33 10486
TEST_TIME 34 10349
TEST_TIME 35 10256
TEST_TIME 36 10202
TEST_TIME 37 10083
TEST_TIME 38 9915
TEST_TIME 39 9975
TEST_TIME 40 9795
TEST_TIME 41 9830
TEST_TIME 42 9614
TEST_TIME 43 9692
TEST_TIME 44 9542
TEST_TIME 45 9489
TEST_TIME 46 9433
TEST_TIME 47 9523
TEST_TIME 48 9415
TEST_TIME 49 9324
TEST_TIME 50 9413
TEST_TIME 51 9342
TEST_TIME 52 9362
TEST_TIME 53 9401
TEST_TIME 54 9389
TEST_TIME 55 9403
TEST_TIME 56 9336
TEST_TIME 57 9471
TEST_TIME 58 9455
TEST_TIME 59 9448
TEST_TIME 60 9572
TEST_TIME 61 9524
TEST_TIME 62 9582
TEST_TIME 63 9639
TEST_TIME 64 9560
TEST_TIME 65 9466
TEST_TIME 66 9464
TEST_TIME 67 9367
TEST_TIME 68 9315
TEST_TIME 69 9315
TEST_TIME 70 9297
TEST_TIME 71 9258
TEST_TIME 72 9216
TEST_TIME 73 9147
TEST_TIME 74 9257
TEST_TIME 75 9271
TEST_TIME 76 9226
TEST_TIME 77 9266
TEST_TIME 78 9298
TEST_TIME 79 9351
TEST_TIME 80 9360
TEST_TIME 81 9439
TEST_TIME 82 9427
TEST_TIME 83 9386
TEST_TIME 84 9429
TEST_TIME 85 9472
TEST_TIME 86 9459
TEST_TIME 87 9405
TEST_TIME 88 9362
TEST_TIME 89 9297
TEST_TIME 90 9280
TEST_TIME 91 9237
TEST_TIME 92 9214
TEST_TIME 93 9191
TEST_TIME 94 9177
TEST_TIME 95 9178
TEST_TIME 96 9132
TEST_TIME 97 9188
TEST_TIME 98 9174
TEST_TIME 99 9193
TEST_TIME 100 9227
TEST_TIME 101 9246
TEST_TIME 102 9228
TEST_TIME 103 9257
TEST_TIME 104 9241
TEST_TIME 105 9399
TEST_TIME 106 9362
TEST_TIME 107 9407
TEST_TIME 108 9354
TEST_TIME 109 9312
TEST_TIME 110 9275
TEST_TIME 111 9242
TEST_TIME 112 9246
TEST_TIME 113 9200
TEST_TIME 114 9206
TEST_TIME 115 9182
TEST_TIME 116 9200
TEST_TIME 117 9164
TEST_TIME 118 9168
TEST_TIME 119 9122
TEST_TIME 120 9064

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-04-2022 22:39:58 to 25-04-2022 22:40:01.

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 1486853 1486853 0 0.00 0 25293 7 0 0
doc_list_biud 1486852 1486852 0 0.00 0 61731 28 0 0
sp_add_invoice_to_stock 4846 4715 131 2.70 28 178158 17645 0 131
sp_cancel_adding_invoice 5920 5920 0 0.00 5 77508 767 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 13942 336 0 0
sp_cancel_customer_reserve 573029 573029 0 0.00 1 24957 58 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 2 7953 83 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 2 10863 141 0 0
sp_cancel_supplier_invoice 6539 6533 6 0.09 2 68891 847 0 6
sp_cancel_supplier_order 410 410 0 0.00 6 1075385 104565 0 0
sp_cancel_write_off 10469 10469 0 0.00 1 23096 121 0 0
sp_client_order 19623 19623 0 0.00 3 28378 391 0 0
sp_customer_reserve 430835 430835 0 0.00 0 91735 175 0 0
sp_fill_shopping_cart 29948 29948 0 0.00 1 17266 47 0 0
sp_fill_shopping_cart_clo_res 430834 430834 0 0.00 0 15628 15 0 0
sp_get_clo_for_invoice 4714 4714 0 0.00 0 18844 365 0 0
sp_kill_cost_storno 17417 17417 0 0.00 0 12507 60 0 0
sp_kill_qstorno_ret_qs2qd 1163470 1163470 0 0.00 0 61727 32 0 0
sp_kill_qty_storno 612187 612187 0 0.00 0 61729 68 0 0
sp_lock_selected_doc 19590 19590 0 0.00 0 10115 6 0 0
sp_make_cost_storno 26237 26237 0 0.00 0 25260 79 0 0
sp_multiply_rows_for_pdistr 26237 26237 0 0.00 0 13206 35 0 0
sp_multiply_rows_for_qdistr 19623 19623 0 0.00 0 18153 188 0 0
sp_pay_from_customer 6677 6677 0 0.00 4 14489 123 0 0
sp_pay_to_supplier 5421 5421 0 0.00 5 23274 177 0 0
sp_payment_common 12098 12098 0 0.00 1 20327 40 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 13932 305 0 0
sp_qd_handle_on_invoice_upd_sts 10633 10633 0 0.00 0 25841 319 0 0
sp_qd_handle_on_reserve_upd_sts 9425 9425 0 0.00 0 19069 64 0 0
sp_reserve_write_off 9425 9425 0 0.00 6 19286 211 0 0
sp_supplier_invoice 5269 5269 0 0.00 7 103901 3552 0 0
sp_supplier_order 5065 5065 0 0.00 5 39045 1316 0 0
srv_aggregate_perf_data 104 104 0 0.00 2944 82244 15296 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2361 148341 51963 0 0
srv_find_qd_qs_mism 1042962 1042962 0 0.00 0 15477 5 0 0
srv_make_invnt_saldo 506 506 0 0.00 31 77935 1274 0 0
srv_make_money_saldo 793 793 0 0.00 1 1164 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 243636 387582 301487 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4717 4717 0 0.00 0 12468 151 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 10202 108 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 7357 29 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 2015 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 1222 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 363 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 4175 25 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 49651 1935 0 0
t$perf-norm:sp_cancel_write_off 897 897 0 0.00 0 4030 10 0 0
t$perf-norm:sp_client_order 19623 19623 0 0.00 0 20185 21 0 0
t$perf-norm:sp_customer_reserve 916 916 0 0.00 0 7329 17 0 0
t$perf-norm:sp_pay_from_customer 6677 6677 0 0.00 0 10000 17 0 0
t$perf-norm:sp_pay_to_supplier 5421 5421 0 0.00 0 10459 14 0 0
t$perf-norm:sp_reserve_write_off 9425 9425 0 0.00 0 6775 16 0 0
t$perf-norm:sp_supplier_invoice 5269 5269 0 0.00 0 6409 23 0 0
t$perf-norm:sp_supplier_order 5065 5065 0 0.00 0 7333 19 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 462 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 621 12 0 0
t$perf-norm:srv_make_invnt_saldo 506 506 0 0.00 0 855 18 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 5363 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9688 9426 262 2.70 0 9441 22 0 262
v_all_customers 36048 36048 0 0.00 0 57 0 0 0
v_all_suppliers 28384 28384 0 0.00 0 20 1 0 0
v_all_wares 39246 39246 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 10165 20 0 0
v_cancel_client_order 3376 3376 0 0.00 0 2877 5 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 2672 6 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 3352 7 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1814 8 0 0
v_cancel_supplier_invoice 760 748 12 1.57 0 3568 16 0 12
v_cancel_supplier_order 820 820 0 0.00 0 10051 17 0 0
v_cancel_write_off 1794 1794 0 0.00 0 4705 16 0 0
v_max_id_clo_ord 5064 5064 0 0.00 0 3857 2 0 0
v_max_id_clo_res 916 916 0 0.00 0 3509 14 0 0
v_max_id_ord_sup 5261 5261 0 0.00 0 2475 3 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 7525 8 0 0
v_max_non_paid_realizn 3340 3340 0 0.00 0 2360 4 0 0
v_min_id_clo_ord 5064 5064 0 0.00 0 12426 18 0 0
v_min_id_clo_res 916 916 0 0.00 0 3788 11 0 0
v_min_id_ord_sup 5261 5261 0 0.00 0 16997 12 0 0
v_min_non_paid_invoice 5421 5421 0 0.00 0 20221 40 0 0
v_min_non_paid_realizn 6677 6677 0 0.00 0 10600 26 0 0
v_our_firm 9872 9872 0 0.00 0 15 1 0 0
v_reserve_write_off 18850 18850 0 0.00 0 5469 6 0 0
x_lock_depdocs_on_canc_invoice 12079 12079 0 0.00 0 20644 125 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 29360 436 0 0
x_make_qstorno_1000_1200 5065 5065 0 0.00 2 39017 1222 0 0
x_make_qstorno_1200_2000 5269 5269 0 0.00 3 103885 3384 0 0
x_make_qstorno_2100_3300 430835 430835 0 0.00 0 91721 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-04-2022 22:40:02 to 25-04-2022 22:40:06.

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
20:08:17 11 8192000000 8811195776 8826691584 14983504 0.182 4 4 5 0 15210240 87456 139280
20:09:18 27286 8192000000 9903618752 10213597184 984211952 10.725 100 89 178 0 1014265056 6560688 23492416
20:10:49 28824 8192000000 9949828496 10276089856 1031493152 11.183 100 85 170 0 1061867696 8676112 21698432
20:12:26 13318 8192000000 9997491328 10336317440 1086298032 11.707 100 88 176 0 1118686256 10450192 21938032
20:13:44 12626 8192000000 10060758672 10411651072 1142181632 12.236 100 82 164 0 1173069696 11211072 19676992
20:14:59 5761 8192000000 10102251952 10461253632 1186171808 12.648 100 81 162 1 1217189536 11865104 19152624
20:16:08 3590 8192000000 10133662208 10500386816 1221799136 12.978 100 89 176 2 1255249440 14211536 19238768
20:17:14 2784 8192000000 10175261840 10554028032 1261055616 13.340 100 82 162 2 1291518592 12849904 17613072
20:18:18 2268 8192000000 10210607312 10589388800 1290704992 13.611 100 92 184 0 1325603248 14202704 20695552
20:19:22 10960 8192000000 10211083488 10606522368 1303743280 13.729 100 66 131 1 1328948640 11070304 14135056
20:20:36 5607 8192000000 10199868240 10610483200 1309814000 13.784 100 33 66 1 1324267680 7140320 7313360
20:21:43 6990 8192000000 10205538640 10614595584 1315695680 13.838 100 35 70 0 1332013760 8432880 7885200
20:22:50 7816 8192000000 10200394624 10619772928 1319877056 13.876 100 19 38 1 1330797792 5943168 4977568
20:24:04 53940 8192000000 10222874624 10641244160 1329505088 13.963 100 33 66 1 1344111568 7025360 7581120
20:26:00 6934 8192000000 10228406000 10648006656 1324006176 13.913 99 20 40 1 1334262816 5515312 4741328
20:27:09 12053 8192000000 10206579584 10622746624 1299041296 13.687 98 22 45 0 1309105248 5404448 4659504
20:28:22 6599 8192000000 10164517040 10573647872 1257395440 13.306 98 14 28 0 1265908816 5743840 2769536
20:29:34 21218 8192000000 10161274832 10570612736 1247577360 13.216 97 23 45 2 1258935536 6030832 5327344
20:30:57 7397 8192000000 10147565840 10556542976 1240313808 13.149 98 33 67 1 1254493376 6432768 7746800
20:32:07 6243 8192000000 10163860896 10562617344 1255519648 13.289 99 19 38 1 1265561968 5735856 4306464
20:33:14 10532 8192000000 10174496480 10583543808 1282403088 13.535 100 18 36 1 1292098432 5798320 3897024
20:34:28 32579 8192000000 10187019248 10604519424 1296651232 13.665 100 20 39 2 1306745168 5227088 4866848
20:36:04 8295 8192000000 10204712944 10619822080 1310585440 13.791 100 29 58 1 1322165424 5011824 6568160
20:37:16 8926 8192000000 10214229856 10628112384 1305108528 13.742 99 27 55 0 1317261072 5228144 6924400
20:38:31 55312 8192000000 10248070672 10659205120 1344352480 14.097 100 26 51 0 1355827600 5792240 5682880
20:40:33 80750 8192000000 10289129360 10704642048 1365968112 14.291 100 48 96 1 1396083888 19462032 10653744
20:43:17 41138 8192000000 10293518112 10720378880 1379805104 14.415 100 35 70 0 1403294736 14305520 9184112
20:45:03 5715 8192000000 10295203424 10716688384 1364333184 14.276 98 54 108 0 1398536736 20894656 13308896
20:46:13 18623 8192000000 10307613632 10743721984 1393428384 14.536 100 35 70 2 1428116160 26240960 8446816
20:47:36 15447 8192000000 10333441360 10765905920 1403106080 14.623 100 36 71 2 1441627088 29040736 9480272
20:49:01 8956 8192000000 10344773120 10769383424 1410663824 14.690 100 53 106 1 1456435984 32827904 12944256
20:50:15 3640 8192000000 10354650688 10769780736 1401625296 14.609 99 67 134 1 1448972560 30601904 16745360
20:51:24 6750 8192000000 10360334144 10778599424 1402467200 14.617 99 61 122 0 1458925440 40994656 15463584
20:52:41 3371 8192000000 10365103824 10780229632 1391958528 14.523 98 75 145 1 1454326752 44512720 17855504
20:53:50 3772 8192000000 10375290496 10790879232 1417490336 14.750 100 66 132 1 1481238656 46899904 16848416
20:54:58 6708 8192000000 10374430688 10786922496 1402784144 14.620 100 77 153 1 1472883088 50622192 19476752
20:56:13 2361 8192000000 10368020832 10779811840 1403793664 14.629 100 86 172 2 1474243600 49202672 21247264
20:57:18 9898 8192000000 10331481264 10755420160 1380125424 14.418 99 36 70 2 1438886400 48801648 9959328
20:58:32 7839 8192000000 10360972080 10786680832 1400269056 14.597 100 39 78 4 1464638480 54181408 10188016
20:59:46 4333 8192000000 10362690032 10773155840 1383245584 14.446 100 57 114 0 1461682976 63535424 14901968
21:00:55 13076 8192000000 10359630304 10786869248 1393236960 14.535 100 36 72 2 1465270704 61165552 10868192
21:02:12 18214 8192000000 10349963536 10791264256 1380567440 14.422 100 38 76 3 1466273216 74161776 11544000
21:03:40 25097 8192000000 10346225296 10780262400 1349379888 14.142 98 26 52 2 1429560272 72339120 7841264
21:05:06 34092 8192000000 10296699808 10740822016 1333386720 13.998 98 18 36 1 1398915888 59996448 5532720
21:06:49 127747 8192000000 10281520064 10727387136 1364796448 14.280 100 26 52 1 1388440192 17278512 6365232
21:09:58 53039 8192000000 10285869872 10747994112 1374179392 14.364 100 16 32 1 1399403168 21409824 3813952
21:11:52 62777 8192000000 10297268016 10739798016 1374305520 14.366 99 32 64 2 1392214528 10600048 7308960
21:13:56 80125 8192000000 10297417584 10750943232 1390410208 14.510 100 16 32 0 1412034528 17562480 4061840
21:16:28 45499 8192000000 10274900784 10721640448 1345760096 14.109 97 15 28 0 1364290576 15126144 3404336
21:18:15 89675 8192000000 10305377104 10759606272 1397444768 14.572 100 18 36 2 1410715920 9079568 4191584
21:20:45 78106 8192000000 10299573280 10756771840 1393087216 14.533 100 10 20 0 1406143248 10714784 2341248
21:23:05 19683 8192000000 10298243696 10747150336 1395539984 14.555 100 26 52 0 1410768864 9125712 6103168
21:24:26 67813 8192000000 10309729360 10766254080 1402174336 14.614 100 15 30 0 1417847232 11989936 3682960
21:26:46 79415 8192000000 10290409216 10749100032 1393099696 14.534 99 18 36 1 1409870688 12303344 4467648
21:29:26 27346 8192000000 10301998880 10765811712 1405003424 14.640 100 19 38 2 1420129696 10290384 4835888
21:30:55 60406 8192000000 10291342256 10745667584 1359377600 14.232 97 16 32 0 1375008320 12197136 3433584
21:32:56 61642 8192000000 10264401248 10719809536 1336596384 14.027 97 19 38 1 1350256576 9474656 4185536
21:34:59 55966 8192000000 10274373760 10737451008 1359900016 14.236 99 19 38 1 1378527120 14370176 4256928
21:37:00 129189 8192000000 10308339552 10779885568 1386069696 14.471 99 12 24 1 1405982592 17394000 2518896
21:40:14 120013 8192000000 10278480112 10734305280 1288063216 13.587 92 7 14 0 1304329040 14739776 1526048
21:43:18 40602 8192000000 10267033184 10711465984 1342807600 14.083 98 46 93 2 1361561952 7689920 11064432
21:45:00 37889 8192000000 10280750976 10708664320 1319712160 13.874 97 55 110 0 1343921856 11278144 12931552
21:46:39 21429 8192000000 10269596016 10703499264 1350019136 14.148 100 42 84 1 1374316272 14303408 9993728
21:48:13 54516 8192000000 10267468688 10724728832 1336599760 14.027 98 8 16 0 1354201344 15682528 1919056
21:50:19 63212 8192000000 10255078944 10711609344 1352595248 14.171 100 13 26 0 1374472032 18869440 3007344
21:52:55 79508 8192000000 10251056640 10708824064 1298171280 13.679 95 20 40 2 1314751024 11607440 4972304
21:56:06 72301 8192000000 10278271648 10742542336 1364950432 14.282 99 16 32 1 1385697728 16746560 4000736
21:58:23 37644 8192000000 10280851360 10732838912 1367801376 14.307 99 23 46 1 1389198080 16378976 5017728
22:00:00 52987 8192000000 10277154784 10724577280 1338118192 14.040 97 13 26 4 1351998816 10725712 3154912
22:01:54 38549 8192000000 10281138032 10734342144 1359908624 14.237 99 38 76 0 1379223536 10897376 8417536
22:03:33 55414 8192000000 10282937824 10723217408 1338502096 14.044 97 27 54 1 1352588864 7818256 6268512
22:05:38 108042 8192000000 10268558000 10725093376 1362355280 14.258 99 28 56 0 1378244864 9079408 6810176
22:08:26 49311 8192000000 10271106432 10734010368 1382293440 14.437 100 11 22 1 1391024448 6109440 2621568
22:10:19 51828 8192000000 10288415344 10752065536 1394553008 14.546 100 8 16 0 1402205616 6148576 1504032
22:12:12 63969 8192000000 10280394304 10740137984 1350187472 14.149 96 15 30 0 1361367104 7820224 3359408
22:14:26 50048 8192000000 10313798608 10762301440 1395647296 14.556 100 40 79 2 1417670928 12829856 9193776
22:16:23 148340 8192000000 10297615728 10753359872 1354407920 14.187 96 14 28 0 1373232224 15846512 2977792
22:19:53 90450 8192000000 10299449056 10746605568 1330749776 13.974 95 20 38 1 1346543808 11337776 4456256
22:22:42 89448 8192000000 10289118928 10761142272 1347132032 14.122 97 17 34 0 1370439296 19582032 3725232
22:25:12 123677 8192000000 10288494368 10730639360 1360518496 14.242 99 15 30 0 1376320256 11806192 3995568
22:28:52 34742 8192000000 10269399536 10726170624 1366716592 14.298 100 28 56 1 1383652736 10748272 6187872
22:30:35 78781 8192000000 10261882864 10722934784 1332156112 13.987 97 6 12 0 1344565920 11150000 1259808
22:32:54 71069 8192000000 10279411200 10739175424 1371716016 14.342 99 12 24 1 1383101152 8660336 2724800
22:35:08 67008 8192000000 10265738928 10722758656 1329353056 13.961 96 20 40 0 1343506928 9905968 4247904
22:37:16 65385 8192000000 9091179088 9329958912 250053472 2.961 19 2 4 0 252269840 1963616 252752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-04-2022 22:40:08 to 25-04-2022 22:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-04-2022 22:40:24 to 25-04-2022 22:40:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237869
MON$OLDEST_ACTIVE               237870
MON$OLDEST_SNAPSHOT             237870
MON$NEXT_TRANSACTION            237870
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-03 16:41:55.3470
MON$PAGES                       2028064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:1670: 
:1671: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1672:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1673: 
:1674: 
:1675: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1676:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1677: 
:1678: 
:1679: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1680:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1681: 
:1682: 
:1683: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1684:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1685: 
:1686: 
:1687: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1688:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1689: 
:1690: 
:1691: mscs-test-161.budzdorov.ru    Mon Apr 25 22:38:26 2022
:1692:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1693: 
--- end of diff output ---

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

25.04.22 22:40:27. There are 3 logs in /var/tmp/logs-oltp30 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-04-25 22:40:29 No severe PSQL-related problems occured
25.04.22 22:40:30. End of report.