Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 19: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 16823092 kB
MemAvailable 31537596 kB
Buffers 0 kB
Cached 14904836 kB
SwapCached 2528 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-24 19:38:15
DTS_END                         2022-04-24 19:38:15.4460
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/37880
TRN_ID                          242834
ATT_ID                          656
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: 9044.09 21911 1085291
customer order: creation 176.58 377 21189
customer order: refuse 14.93 380 1792
order to supplier: creation 47.53 1289 5704
order to supplier: removal 3.38 97954 406
invoice (draft): creation 47.81 3637 5737
invoice (draft): removal 49.83 833 5979
invoice accept: apply 42.52 18551 5102
invoice accept: cancel 44.33 790 5320
customer reserve: creation 3951.22 182 474146
customer reserve: removal 4370.68 59 524482
realization accept: apply 85.48 191 10258
realization accept: cancel 78.33 110 9399
payment from customer: creation 59.67 128 7160
payment from customer: removal 5.93 89 712
payment to supplier: creation 49.49 147 5939
payment to supplier: removal 3.48 56 417
service: total inventory turnovers 5.10 1004 612
service: total monetary turnovers 6.79 32 815
service: aggregate perf. data 0.98 19547 118
service: refresh index statistics 0.03 292867 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-04-2022 19:39:37 to 24-04-2022 19:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6930
TEST_TIME 2 5583
TEST_TIME 3 5581
TEST_TIME 4 5159
TEST_TIME 5 5706
TEST_TIME 6 6163
TEST_TIME 7 5968
TEST_TIME 8 6051
TEST_TIME 9 5965
TEST_TIME 10 6212
TEST_TIME 11 6518
TEST_TIME 12 6760
TEST_TIME 13 7048
TEST_TIME 14 7517
TEST_TIME 15 7912
TEST_TIME 16 8449
TEST_TIME 17 8456
TEST_TIME 18 8477
TEST_TIME 19 8724
TEST_TIME 20 9499
TEST_TIME 21 9805
TEST_TIME 22 10213
TEST_TIME 23 10853
TEST_TIME 24 10936
TEST_TIME 25 11146
TEST_TIME 26 11056
TEST_TIME 27 10918
TEST_TIME 28 10898
TEST_TIME 29 10758
TEST_TIME 30 10620
TEST_TIME 31 10328
TEST_TIME 32 10374
TEST_TIME 33 10185
TEST_TIME 34 10309
TEST_TIME 35 10175
TEST_TIME 36 10056
TEST_TIME 37 9906
TEST_TIME 38 9783
TEST_TIME 39 9807
TEST_TIME 40 9623
TEST_TIME 41 9626
TEST_TIME 42 9529
TEST_TIME 43 9562
TEST_TIME 44 9433
TEST_TIME 45 9469
TEST_TIME 46 9432
TEST_TIME 47 9515
TEST_TIME 48 9456
TEST_TIME 49 9453
TEST_TIME 50 9448
TEST_TIME 51 9420
TEST_TIME 52 9351
TEST_TIME 53 9439
TEST_TIME 54 9625
TEST_TIME 55 9550
TEST_TIME 56 9667
TEST_TIME 57 9659
TEST_TIME 58 9719
TEST_TIME 59 9749
TEST_TIME 60 9643
TEST_TIME 61 9513
TEST_TIME 62 9557
TEST_TIME 63 9531
TEST_TIME 64 9475
TEST_TIME 65 9502
TEST_TIME 66 9452
TEST_TIME 67 9346
TEST_TIME 68 9311
TEST_TIME 69 9240
TEST_TIME 70 9172
TEST_TIME 71 9294
TEST_TIME 72 9293
TEST_TIME 73 9268
TEST_TIME 74 9262
TEST_TIME 75 9299
TEST_TIME 76 9354
TEST_TIME 77 9364
TEST_TIME 78 9381
TEST_TIME 79 9342
TEST_TIME 80 9355
TEST_TIME 81 9361
TEST_TIME 82 9364
TEST_TIME 83 9421
TEST_TIME 84 9409
TEST_TIME 85 9314
TEST_TIME 86 9266
TEST_TIME 87 9275
TEST_TIME 88 9240
TEST_TIME 89 9173
TEST_TIME 90 9164
TEST_TIME 91 9103
TEST_TIME 92 9123
TEST_TIME 93 9081
TEST_TIME 94 9066
TEST_TIME 95 9058
TEST_TIME 96 9074
TEST_TIME 97 9111
TEST_TIME 98 9115
TEST_TIME 99 9163
TEST_TIME 100 9117
TEST_TIME 101 9222
TEST_TIME 102 9185
TEST_TIME 103 9145
TEST_TIME 104 9144
TEST_TIME 105 9211
TEST_TIME 106 9246
TEST_TIME 107 9262
TEST_TIME 108 9267
TEST_TIME 109 9278
TEST_TIME 110 9227
TEST_TIME 111 9199
TEST_TIME 112 9157
TEST_TIME 113 9141
TEST_TIME 114 9137
TEST_TIME 115 9111
TEST_TIME 116 9123
TEST_TIME 117 9109
TEST_TIME 118 9097
TEST_TIME 119 9119
TEST_TIME 120 9048

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-04-2022 19:39:40 to 24-04-2022 19:39:44.

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 1522501 1522501 0 0.00 0 16568 6 0 0
doc_list_biud 1522500 1522500 0 0.00 0 67822 26 0 0
sp_add_invoice_to_stock 5189 5102 87 1.67 23 178252 18551 0 87
sp_cancel_adding_invoice 5320 5320 0 0.00 5 53221 791 0 0
sp_cancel_client_order 1792 1792 0 0.00 4 11082 380 0 0
sp_cancel_customer_reserve 524482 524482 0 0.00 1 67825 59 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 2 5736 89 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 793 56 0 0
sp_cancel_supplier_invoice 5987 5979 8 0.13 2 17732 833 0 8
sp_cancel_supplier_order 406 406 0 0.00 5 940691 97954 0 0
sp_cancel_write_off 9399 9399 0 0.00 1 25806 110 0 0
sp_client_order 21189 21189 0 0.00 4 21532 378 0 0
sp_customer_reserve 474146 474146 0 0.00 0 44844 183 0 0
sp_fill_shopping_cart 32622 32622 0 0.00 1 12654 43 0 0
sp_fill_shopping_cart_clo_res 474136 474136 0 0.00 0 16521 14 0 0
sp_get_clo_for_invoice 5091 5091 0 0.00 0 29176 317 0 0
sp_kill_cost_storno 15848 15848 0 0.00 0 7146 55 0 0
sp_kill_qstorno_ret_qs2qd 1064748 1064748 0 0.00 0 67813 32 0 0
sp_kill_qty_storno 562726 562726 0 0.00 0 67822 70 0 0
sp_lock_selected_doc 21120 21120 0 0.00 0 8359 6 0 0
sp_make_cost_storno 28448 28448 0 0.00 0 16560 54 0 0
sp_multiply_rows_for_pdistr 28448 28448 0 0.00 0 6140 30 0 0
sp_multiply_rows_for_qdistr 21189 21189 0 0.00 0 13982 166 0 0
sp_pay_from_customer 7160 7160 0 0.00 4 11489 128 0 0
sp_pay_to_supplier 5939 5939 0 0.00 4 11211 148 0 0
sp_payment_common 13099 13099 0 0.00 1 8269 27 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 9704 347 0 0
sp_qd_handle_on_invoice_upd_sts 10411 10411 0 0.00 0 27164 341 0 0
sp_qd_handle_on_reserve_upd_sts 10257 10257 0 0.00 0 26976 61 0 0
sp_reserve_write_off 10258 10258 0 0.00 6 27046 191 0 0
sp_supplier_invoice 5737 5737 0 0.00 6 75241 3638 0 0
sp_supplier_order 5704 5704 0 0.00 5 28529 1289 0 0
srv_aggregate_perf_data 118 118 0 0.00 2356 353248 19547 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1877 107781 41645 0 0
srv_find_qd_qs_mism 1035506 1035506 0 0.00 0 12589 5 0 0
srv_make_invnt_saldo 612 612 0 0.00 36 35837 1004 0 0
srv_make_money_saldo 815 815 0 0.00 1 6981 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 82135 432397 292867 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5102 5102 0 0.00 0 11754 154 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 2608 94 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 1914 11 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 2233 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 2352 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 5667 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 461 11 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 22208 1600 0 0
t$perf-norm:sp_cancel_write_off 959 959 0 0.00 0 776 6 0 0
t$perf-norm:sp_client_order 21189 21189 0 0.00 0 9123 18 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 599 3 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 5869 12 0 0
t$perf-norm:sp_pay_to_supplier 5939 5939 0 0.00 0 5794 10 0 0
t$perf-norm:sp_reserve_write_off 10258 10258 0 0.00 0 5860 12 0 0
t$perf-norm:sp_supplier_invoice 5737 5737 0 0.00 0 7817 18 0 0
t$perf-norm:sp_supplier_order 5704 5704 0 0.00 0 8154 18 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 201 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 70 2 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 368 12 0 0
t$perf-norm:srv_make_money_saldo 815 815 0 0.00 0 737 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 18 6 0 0
v_add_invoice_to_stock 10354 10180 174 1.68 0 6948 16 0 174
v_all_customers 38568 38568 0 0.00 0 28 1 0 0
v_all_suppliers 31514 31514 0 0.00 0 22 1 0 0
v_all_wares 42378 42378 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 1052 7 0 0
v_cancel_client_order 3584 3584 0 0.00 0 10525 11 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 5709 15 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 10508 18 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 658 4 0 0
v_cancel_supplier_invoice 826 810 16 1.93 0 656 14 0 16
v_cancel_supplier_order 812 812 0 0.00 0 580 4 0 0
v_cancel_write_off 1918 1918 0 0.00 0 7409 25 0 0
v_max_id_clo_ord 5703 5703 0 0.00 0 309 1 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 3448 12 0 0
v_max_id_ord_sup 5729 5729 0 0.00 0 3657 4 0 0
v_max_non_paid_invoice 1614 1614 0 0.00 0 1957 3 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 1957 4 0 0
v_min_id_clo_ord 5703 5703 0 0.00 0 3587 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 9463 16 0 0
v_min_id_ord_sup 5729 5729 0 0.00 0 5407 7 0 0
v_min_non_paid_invoice 5939 5939 0 0.00 0 6222 20 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 8261 20 0 0
v_our_firm 10374 10374 0 0.00 0 141 1 0 0
v_reserve_write_off 20514 20514 0 0.00 0 10526 9 0 0
x_lock_depdocs_on_canc_invoice 10894 10894 0 0.00 0 23658 114 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 21183 417 0 0
x_make_qstorno_1000_1200 5704 5704 0 0.00 1 28511 1209 0 0
x_make_qstorno_1200_2000 5737 5737 0 0.00 3 70497 3477 0 0
x_make_qstorno_2100_3300 474146 474146 0 0.00 0 44843 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 19:39:45 to 24-04-2022 19:39:49.

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
17:08:19 16 8192000000 8811778704 8827150336 15633744 0.190 4 4 6 0 15871040 88016 149280
17:09:21 31459 8192000000 9903067248 10213265408 979846384 10.683 100 80 160 0 1007024112 5892928 21284800
17:10:58 13641 8192000000 9947066736 10268815360 1030312000 11.171 100 83 166 0 1059632240 8128768 21191472
17:12:18 16375 8192000000 9980187552 10311118848 1064731952 11.502 100 86 172 1 1095535264 9529344 21273968
17:13:36 14082 8192000000 10043621744 10387247104 1125280544 12.077 100 84 169 0 1156284592 11230800 19773248
17:14:56 7302 8192000000 10087557440 10445692928 1172335088 12.519 100 79 158 1 1201827952 11044848 18448016
17:16:05 5289 8192000000 10132182272 10502340608 1219753536 12.959 100 76 152 0 1250878736 14076128 17049072
17:17:16 2944 8192000000 10168632256 10543714304 1259841536 13.329 100 92 184 1 1293388160 12817568 20729056
17:18:20 2570 8192000000 10209173792 10589708288 1288780544 13.593 100 94 187 1 1323764416 14376272 20607600
17:19:24 5168 8192000000 10216556288 10606592000 1310251840 13.788 100 83 165 2 1339573680 10765856 18555984
17:20:29 3696 8192000000 10221144448 10621898752 1316961696 13.849 100 50 100 0 1342433072 14796800 10674576
17:21:33 9265 8192000000 10206154400 10621796352 1321682480 13.892 100 29 61 1 1335682288 7489168 6510640
17:22:46 74159 8192000000 10216834400 10638966784 1329803856 13.965 100 28 53 2 1341590000 5096416 6689728
17:25:00 2675 8192000000 10243122608 10655334400 1339375792 14.052 100 51 103 3 1358465632 7451840 11638000
17:26:06 5648 8192000000 10243647088 10662875136 1342993920 14.084 100 33 65 2 1361053776 10848224 7211632
17:27:13 58843 8192000000 10241291504 10638811136 1316120416 13.842 98 45 88 3 1332796176 5667408 11008352
17:29:13 5768 8192000000 10181442416 10581266432 1257369248 13.306 97 34 68 0 1270951888 6033840 7548800
17:30:21 7536 8192000000 10153782848 10557001728 1244553664 13.188 98 19 38 1 1254062000 5393712 4114624
17:31:31 12982 8192000000 10148254192 10533490688 1225154544 13.009 98 29 56 2 1240964448 9570368 6239536
17:32:46 13312 8192000000 10129325936 10530361344 1201573904 12.791 96 28 56 2 1214801200 6907712 6319584
17:34:02 4476 8192000000 10154255360 10554314752 1254834688 13.283 99 32 64 1 1268256320 5488208 7933424
17:35:09 4887 8192000000 10183833488 10589069312 1287395072 13.580 100 31 62 1 1301377184 6248656 7733456
17:36:16 19077 8192000000 10203364368 10608979968 1304615936 13.737 100 26 52 1 1316117184 6219600 5281648
17:37:58 8531 8192000000 10202321376 10619691008 1312517520 13.809 100 17 34 0 1320817952 4620448 3679984
17:39:12 8882 8192000000 10249848112 10654769152 1336292016 14.024 100 59 114 3 1359868688 10616032 12960640
17:40:24 19326 8192000000 10267171616 10683551744 1354256144 14.186 100 52 105 1 1381417856 15371024 11790688
17:41:49 22968 8192000000 10283969728 10704687104 1372527936 14.350 100 42 82 1 1400756672 18629808 9598928
17:43:31 3016 8192000000 10318160960 10732515328 1387973488 14.488 100 62 123 0 1426052576 23043776 15035312
17:44:39 18658 8192000000 10319006160 10742882304 1390553504 14.511 100 54 106 0 1429411616 26369264 12488848
17:46:06 11683 8192000000 10330890192 10751741952 1395691040 14.557 99 55 109 1 1442667168 32897376 14078752
17:47:21 2639 8192000000 10363229664 10775076864 1415168720 14.730 100 84 167 1 1470532128 35518384 19845024
17:48:28 4545 8192000000 10367639696 10784739328 1420092192 14.774 100 59 118 1 1474957280 40275584 14589504
17:49:36 3572 8192000000 10369555712 10780786688 1411775824 14.700 100 73 144 0 1473659776 44236640 17647312
17:50:44 9757 8192000000 10375113584 10791739392 1403507856 14.626 99 71 142 1 1469575856 47655312 18412688
17:52:00 2623 8192000000 10347998816 10754879488 1381178544 14.427 100 83 164 0 1451800800 50864224 19758032
17:53:07 1875 8192000000 10350758672 10763141120 1385547552 14.466 100 74 146 0 1453920048 49174592 19197904
17:54:13 12737 8192000000 10341650752 10760474624 1380841792 14.424 100 61 122 0 1446331216 49686352 15803072
17:55:43 10816 8192000000 10316338528 10736467968 1356995728 14.210 99 49 98 0 1407604272 38453392 12155152
17:56:58 3200 8192000000 10326443776 10735976448 1352298336 14.168 99 88 175 0 1422211008 46681088 23231584
17:58:07 6835 8192000000 10324477344 10742792192 1372467920 14.349 100 50 100 2 1426123344 40095008 13560416
17:59:18 21756 8192000000 10295387648 10725240832 1344426288 14.097 98 24 48 0 1385549968 34144784 6978896
18:00:45 7567 8192000000 10289657264 10719010816 1357627264 14.216 100 32 65 1 1395894208 29198928 9068016
18:01:57 26101 8192000000 10293353024 10731868160 1360061472 14.238 99 17 34 0 1386763296 21614208 5087616
18:03:30 39544 8192000000 10283156544 10733678592 1359583456 14.234 99 25 50 2 1384208720 18406592 6218672
18:05:15 16511 8192000000 10279197984 10708688896 1339082768 14.049 98 36 72 2 1363445568 16371264 7991536
18:06:33 26325 8192000000 10273840864 10716561408 1354615472 14.189 99 22 44 0 1371948640 12309328 5023840
18:09:01 57459 8192000000 10275284000 10713796608 1338205920 14.041 98 21 42 2 1360394768 17471952 4716896
18:11:54 50493 8192000000 10286293360 10739044352 1377997280 14.399 100 14 28 0 1400812560 20024080 2791200
18:13:49 102617 8192000000 10286882592 10734014464 1346073296 14.112 97 31 62 1 1361979744 9405024 6501424
18:16:33 17515 8192000000 10291163104 10741514240 1391782992 14.522 100 37 71 2 1412934896 12493296 8658608
18:17:57 52370 8192000000 10302516192 10748325888 1384466976 14.456 99 18 36 1 1399694912 10816000 4411936
18:19:57 74007 8192000000 10306468448 10771427328 1399889552 14.594 100 17 34 0 1420875616 16131360 4854704
18:22:13 56973 8192000000 10305085840 10767130624 1399754976 14.593 100 28 56 1 1422413920 15845120 6813824
18:24:12 52699 8192000000 10311497184 10776961024 1407833168 14.665 100 32 64 0 1428854048 12950960 8069920
18:26:11 39324 8192000000 10330990464 10777772032 1411682688 14.699 100 39 77 0 1430754048 9483024 9588336
18:27:59 17560 8192000000 10319683248 10774097920 1412317824 14.705 100 21 42 0 1424689936 7484688 4887424
18:29:20 36175 8192000000 10347124816 10796142592 1413589392 14.716 99 41 82 0 1437733968 14698368 9446208
18:30:58 56630 8192000000 10330503968 10799566848 1398242432 14.579 98 19 38 1 1421851088 19029360 4579296
18:32:55 51788 8192000000 10324474160 10774323200 1389391664 14.500 98 12 24 1 1400954752 9227600 2335488
18:34:48 107781 8192000000 10318848800 10786480128 1392872496 14.531 99 7 14 0 1409788944 15557056 1359392
18:37:36 34818 8192000000 10312295456 10757988352 1328070496 13.950 94 25 49 0 1346888960 13458480 5359984
18:39:14 93217 8192000000 10291849808 10743140352 1360273536 14.240 98 33 66 0 1381041504 12508112 8259856
18:41:52 21995 8192000000 10305678496 10740436992 1385169888 14.463 100 54 108 1 1407182368 9763952 12248528
18:43:16 53713 8192000000 10269768064 10724327424 1322207024 13.897 95 28 56 0 1336572960 7961712 6404224
18:45:16 30680 8192000000 10262334576 10716004352 1332234144 13.987 97 11 22 1 1345652096 10802000 2615952
18:46:50 81068 8192000000 10259632544 10712236032 1347490832 14.125 99 19 38 0 1365502464 13049664 4961968
18:49:17 51524 8192000000 10249517664 10706706432 1313301632 13.816 97 7 13 0 1330323680 15356400 1665648
18:51:13 63205 8192000000 10254549248 10698235904 1313765840 13.820 97 22 44 0 1330017104 11356560 4894704
18:54:33 41333 8192000000 10251264720 10700087296 1327480320 13.944 98 29 59 1 1342756128 9017744 6258064
18:56:17 48732 8192000000 10263652192 10704236544 1339213488 14.050 99 33 66 0 1356603296 10151680 7238128
18:58:07 45350 8192000000 10287279136 10727190528 1343916432 14.093 98 26 52 0 1361019088 11333136 5769520
18:59:54 57829 8192000000 10296350544 10735710208 1368245792 14.311 100 29 57 0 1385027488 10505552 6276144
19:01:54 99766 8192000000 10276215232 10724339712 1350586288 14.153 98 16 32 0 1368229056 13750704 3892064
19:04:36 65922 8192000000 10282347184 10738913280 1374965920 14.372 100 21 41 2 1392631536 13269024 4396592
19:06:42 83638 8192000000 10274209568 10730418176 1368828768 14.317 99 6 12 1 1377690800 7417632 1444400
19:09:13 60226 8192000000 10290495616 10752937984 1398860560 14.585 100 14 28 0 1408786592 7001776 2924256
19:11:18 71388 8192000000 10279858496 10745561088 1359932992 14.237 97 6 12 1 1368383792 7306512 1144288
19:13:29 44997 8192000000 10295589584 10754244608 1389005120 14.497 99 21 42 0 1402287664 8678960 4603584
19:15:18 51500 8192000000 10313915968 10767212544 1394374192 14.545 99 14 28 1 1408788000 11231472 3182336
19:17:15 69097 8192000000 10316750864 10779811840 1383960784 14.452 98 9 18 1 1400103488 14060000 2082704
19:19:24 81734 8192000000 10298519936 10756653056 1365925536 14.291 97 9 18 0 1384390544 16336592 2128416
19:21:51 33166 8192000000 10318644304 10779787264 1406963744 14.657 100 35 69 0 1426602848 11631696 8007408
19:23:32 82021 8192000000 10299781072 10753896448 1320696784 13.883 94 10 20 0 1336378368 13468240 2213344
19:26:23 42733 8192000000 10295193072 10737917952 1334980608 14.012 96 29 57 2 1351518912 9622192 6916112
19:28:06 42894 8192000000 10278098656 10732380160 1363515856 14.269 99 5 10 0 1376708432 11971664 1220912
19:30:08 36266 8192000000 10282138128 10731118592 1369789408 14.325 100 26 53 0 1388462032 13087424 5585200
19:31:57 64090 8192000000 10268895696 10729922560 1349159040 14.140 98 13 26 0 1362866512 10755360 2952112
19:34:01 62137 8192000000 10250895824 10711281664 1334512320 14.008 98 12 24 1 1347914240 10435504 2966416
19:36:04 47853 8192000000 10252892224 10709700608 1325104128 13.923 98 8 16 0 1337568048 10462144 2001776
19:37:53 31267 8192000000 9207272544 9472344064 354208224 4.144 26 4 8 0 358786416 3658672 919520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-04-2022 19:39:51 to 24-04-2022 19:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-04-2022 19:40:07 to 24-04-2022 19:40:09.

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          243169
MON$OLDEST_ACTIVE               243170
MON$OLDEST_SNAPSHOT             243170
MON$NEXT_TRANSACTION            243170
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                       2010816
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 ---
:1581: 
:1582: mscs-test-161.budzdorov.ru    Sun Apr 24 19:38:26 2022
:1583:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1584: 
:1585: 
:1586: mscs-test-161.budzdorov.ru    Sun Apr 24 19:38:32 2022
:1587:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1588: 
--- end of diff output ---

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

24.04.22 19:40:10. 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-24 19:40:12 No severe PSQL-related problems occured
24.04.22 19:40:13. End of report.