Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16659164 kB
MemAvailable 31532500 kB
Buffers 0 kB
Cached 15041096 kB
SwapCached 2688 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-21 19:38:14
DTS_END                         2022-04-21 19:38:14.4650
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/48460
TRN_ID                          239140
ATT_ID                          722
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: 9094.34 24064 1091321
customer order: creation 171.29 364 20555
customer order: refuse 15.43 387 1852
order to supplier: creation 47.27 1316 5672
order to supplier: removal 3.39 113410 407
invoice (draft): creation 46.26 3225 5551
invoice (draft): removal 52.53 867 6303
invoice accept: apply 41.12 18963 4934
invoice accept: cancel 47.34 760 5681
customer reserve: creation 3776.80 187 453216
customer reserve: removal 4586.18 66 550342
realization accept: apply 84.34 197 10121
realization accept: cancel 88.90 115 10668
payment from customer: creation 60.85 108 7302
payment from customer: removal 6.46 69 775
payment to supplier: creation 49.93 127 5991
payment to supplier: removal 3.43 83 412
service: total inventory turnovers 5.10 1063 612
service: total monetary turnovers 6.88 28 825
service: aggregate perf. data 0.82 20631 98
service: refresh index statistics 0.03 319313 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5116
TEST_TIME 2 4366
TEST_TIME 3 4809
TEST_TIME 4 4705
TEST_TIME 5 5016
TEST_TIME 6 4767
TEST_TIME 7 4888
TEST_TIME 8 4946
TEST_TIME 9 5054
TEST_TIME 10 5400
TEST_TIME 11 6181
TEST_TIME 12 6082
TEST_TIME 13 6364
TEST_TIME 14 6589
TEST_TIME 15 7000
TEST_TIME 16 7362
TEST_TIME 17 7385
TEST_TIME 18 7628
TEST_TIME 19 7934
TEST_TIME 20 8373
TEST_TIME 21 8189
TEST_TIME 22 9155
TEST_TIME 23 9216
TEST_TIME 24 9283
TEST_TIME 25 9217
TEST_TIME 26 9414
TEST_TIME 27 9655
TEST_TIME 28 10021
TEST_TIME 29 10351
TEST_TIME 30 11123
TEST_TIME 31 10863
TEST_TIME 32 10642
TEST_TIME 33 10374
TEST_TIME 34 10360
TEST_TIME 35 10383
TEST_TIME 36 10192
TEST_TIME 37 10080
TEST_TIME 38 10075
TEST_TIME 39 10106
TEST_TIME 40 10133
TEST_TIME 41 9974
TEST_TIME 42 9901
TEST_TIME 43 9949
TEST_TIME 44 9929
TEST_TIME 45 9839
TEST_TIME 46 9754
TEST_TIME 47 9681
TEST_TIME 48 9611
TEST_TIME 49 9472
TEST_TIME 50 9390
TEST_TIME 51 9298
TEST_TIME 52 9277
TEST_TIME 53 9480
TEST_TIME 54 9529
TEST_TIME 55 9527
TEST_TIME 56 9411
TEST_TIME 57 9360
TEST_TIME 58 9436
TEST_TIME 59 9448
TEST_TIME 60 9433
TEST_TIME 61 9428
TEST_TIME 62 9512
TEST_TIME 63 9550
TEST_TIME 64 9547
TEST_TIME 65 9575
TEST_TIME 66 9629
TEST_TIME 67 9609
TEST_TIME 68 9634
TEST_TIME 69 9620
TEST_TIME 70 9636
TEST_TIME 71 9599
TEST_TIME 72 9619
TEST_TIME 73 9564
TEST_TIME 74 9507
TEST_TIME 75 9462
TEST_TIME 76 9395
TEST_TIME 77 9361
TEST_TIME 78 9448
TEST_TIME 79 9384
TEST_TIME 80 9364
TEST_TIME 81 9334
TEST_TIME 82 9254
TEST_TIME 83 9224
TEST_TIME 84 9262
TEST_TIME 85 9234
TEST_TIME 86 9198
TEST_TIME 87 9258
TEST_TIME 88 9253
TEST_TIME 89 9258
TEST_TIME 90 9235
TEST_TIME 91 9254
TEST_TIME 92 9314
TEST_TIME 93 9285
TEST_TIME 94 9412
TEST_TIME 95 9496
TEST_TIME 96 9469
TEST_TIME 97 9484
TEST_TIME 98 9442
TEST_TIME 99 9419
TEST_TIME 100 9347
TEST_TIME 101 9316
TEST_TIME 102 9264
TEST_TIME 103 9273
TEST_TIME 104 9268
TEST_TIME 105 9243
TEST_TIME 106 9195
TEST_TIME 107 9212
TEST_TIME 108 9195
TEST_TIME 109 9153
TEST_TIME 110 9129
TEST_TIME 111 9088
TEST_TIME 112 9076
TEST_TIME 113 9047
TEST_TIME 114 9131
TEST_TIME 115 9158
TEST_TIME 116 9141
TEST_TIME 117 9124
TEST_TIME 118 9135
TEST_TIME 119 9169
TEST_TIME 120 9100

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-04-2022 19:39:06 to 21-04-2022 19:39:09.

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 1511317 1511317 0 0.00 0 33544 7 0 0
doc_list_biud 1511315 1511315 0 0.00 0 36338 30 0 0
sp_add_invoice_to_stock 5057 4934 123 2.43 52 230469 18963 0 123
sp_cancel_adding_invoice 5681 5681 0 0.00 4 70849 760 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 15531 387 0 0
sp_cancel_customer_reserve 550342 550342 0 0.00 1 36344 67 0 0
sp_cancel_pay_from_customer 775 775 0 0.00 2 3278 70 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 4458 84 0 0
sp_cancel_supplier_invoice 6315 6303 12 0.19 2 19313 867 0 12
sp_cancel_supplier_order 407 407 0 0.00 9 1025026 113410 0 0
sp_cancel_write_off 10668 10668 0 0.00 1 13394 115 0 0
sp_client_order 20555 20555 0 0.00 3 34305 364 0 0
sp_customer_reserve 453216 453216 0 0.00 0 66516 188 0 0
sp_fill_shopping_cart 31773 31773 0 0.00 1 13675 41 0 0
sp_fill_shopping_cart_clo_res 453208 453208 0 0.00 0 16952 16 0 0
sp_get_clo_for_invoice 4926 4926 0 0.00 0 21338 365 0 0
sp_kill_cost_storno 17536 17536 0 0.00 0 9487 59 0 0
sp_kill_qstorno_ret_qs2qd 1118062 1118062 0 0.00 0 36324 36 0 0
sp_kill_qty_storno 590299 590299 0 0.00 0 36336 77 0 0
sp_lock_selected_doc 20982 20982 0 0.00 0 8300 5 0 0
sp_make_cost_storno 28340 28340 0 0.00 0 33478 57 0 0
sp_multiply_rows_for_pdistr 28340 28340 0 0.00 0 33491 32 0 0
sp_multiply_rows_for_qdistr 20555 20555 0 0.00 0 34289 175 0 0
sp_pay_from_customer 7302 7302 0 0.00 4 10256 108 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 10715 127 0 0
sp_payment_common 13292 13292 0 0.00 1 9495 28 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 15526 353 0 0
sp_qd_handle_on_invoice_upd_sts 10606 10606 0 0.00 0 16227 332 0 0
sp_qd_handle_on_reserve_upd_sts 10121 10121 0 0.00 0 12623 67 0 0
sp_reserve_write_off 10121 10121 0 0.00 6 33599 197 0 0
sp_supplier_invoice 5551 5551 0 0.00 6 75185 3226 0 0
sp_supplier_order 5672 5672 0 0.00 5 50814 1316 0 0
srv_aggregate_perf_data 98 98 0 0.00 2766 346476 20631 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 3369 131963 45714 0 0
srv_find_qd_qs_mism 1043572 1043572 0 0.00 0 16783 6 0 0
srv_make_invnt_saldo 612 612 0 0.00 32 53554 1064 0 0
srv_make_money_saldo 825 825 0 0.00 1 1388 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 154977 567138 319314 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4934 4934 0 0.00 0 12586 164 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 6339 102 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 11120 16 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 2728 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 775 775 0 0.00 0 2616 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 1498 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 572 10 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 38317 1725 0 0
t$perf-norm:sp_cancel_write_off 1055 1055 0 0.00 0 1247 6 0 0
t$perf-norm:sp_client_order 20555 20555 0 0.00 0 12862 17 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 1634 8 0 0
t$perf-norm:sp_pay_from_customer 7302 7302 0 0.00 0 33581 15 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 33495 17 0 0
t$perf-norm:sp_reserve_write_off 10121 10121 0 0.00 0 6419 8 0 0
t$perf-norm:sp_supplier_invoice 5551 5551 0 0.00 0 6120 18 0 0
t$perf-norm:sp_supplier_order 5672 5672 0 0.00 0 6878 17 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1068 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2651 78 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 828 15 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 2875 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10096 9850 246 2.43 0 34493 24 0 246
v_all_customers 37564 37564 0 0.00 0 21 1 0 0
v_all_suppliers 31080 31080 0 0.00 0 24 1 0 0
v_all_wares 41108 41108 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2326 10 0 0
v_cancel_client_order 3704 3704 0 0.00 0 6444 5 0 0
v_cancel_customer_prepayment 1550 1550 0 0.00 0 2911 6 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 5139 10 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1001 8 0 0
v_cancel_supplier_invoice 808 784 24 2.97 0 2401 16 0 24
v_cancel_supplier_order 814 814 0 0.00 0 837 4 0 0
v_cancel_write_off 2110 2110 0 0.00 0 5998 14 0 0
v_max_id_clo_ord 5671 5671 0 0.00 0 2906 3 0 0
v_max_id_clo_res 936 936 0 0.00 0 1878 9 0 0
v_max_id_ord_sup 5547 5547 0 0.00 0 5589 4 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 1059 4 0 0
v_max_non_paid_realizn 3872 3872 0 0.00 0 2531 5 0 0
v_min_id_clo_ord 5671 5671 0 0.00 0 3998 13 0 0
v_min_id_clo_res 936 936 0 0.00 0 5251 12 0 0
v_min_id_ord_sup 5547 5547 0 0.00 0 4190 5 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 9490 22 0 0
v_min_non_paid_realizn 7301 7301 0 0.00 0 8307 18 0 0
v_our_firm 10402 10402 0 0.00 0 16 1 0 0
v_reserve_write_off 20240 20240 0 0.00 0 6647 7 0 0
x_lock_depdocs_on_canc_invoice 11592 11592 0 0.00 0 20547 128 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 2981 318 0 0
x_make_qstorno_1000_1200 5672 5672 0 0.00 1 50803 1222 0 0
x_make_qstorno_1200_2000 5551 5551 0 0.00 3 75138 3090 0 0
x_make_qstorno_2100_3300 453216 453216 0 0.00 0 66513 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 19:39:10 to 21-04-2022 19:39:14.

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:18 15 8192000000 8812007088 8827473920 15757568 0.191 4 4 5 0 15984304 87456 139280
17:09:18 34944 8192000000 9895427088 10203619328 974381344 10.629 100 89 178 0 1004193984 5878912 23933728
17:11:05 15402 8192000000 9929122304 10247008256 1012223696 10.997 100 85 170 0 1041885600 7674016 21987888
17:12:25 11773 8192000000 9960823280 10292011008 1048006496 11.342 100 75 150 0 1075734992 8752336 18976160
17:13:48 3361 8192000000 10027170368 10374049792 1119570784 12.023 100 76 151 1 1147567408 10114128 17882496
17:14:57 10909 8192000000 10082926016 10443395072 1168826928 12.486 100 80 157 3 1198870128 11902736 18140464
17:16:12 6430 8192000000 10127528064 10492907520 1212086288 12.888 100 82 162 0 1243385424 13018976 18280160
17:17:22 3645 8192000000 10154800480 10531360768 1240956848 13.155 100 75 148 3 1269567680 12649440 15961392
17:18:27 2538 8192000000 10184895952 10565701632 1264452000 13.371 100 85 171 0 1298304608 14833792 19018816
17:19:30 1571 8192000000 10215574976 10596810752 1294003184 13.641 100 93 186 0 1329316304 14233824 21079296
17:20:33 11898 8192000000 10206184624 10605072384 1301970352 13.713 100 59 121 0 1322396848 6308176 14118320
17:21:47 6778 8192000000 10211717584 10610462720 1309777712 13.784 100 39 78 4 1325590880 6436976 9376192
17:22:55 8318 8192000000 10212593088 10626310144 1321382384 13.889 100 28 56 0 1334419040 6339408 6697248
17:24:07 6886 8192000000 10215325824 10634129408 1328203664 13.951 100 21 41 3 1339275008 6172080 4899264
17:25:17 7159 8192000000 10219922912 10639491072 1333922720 14.003 100 22 44 0 1345359728 6429040 5007968
17:26:25 18490 8192000000 10226245696 10650406912 1337440352 14.034 100 33 66 3 1351326240 7012192 6873696
17:27:45 6329 8192000000 10223318896 10644213760 1311841040 13.803 98 25 50 2 1323690240 5904176 5945024
17:28:53 9345 8192000000 10197640000 10617425920 1300518240 13.700 99 6 12 1 1307261248 5278160 1464848
17:30:08 14464 8192000000 10162409984 10572906496 1237765280 13.126 96 13 26 1 1245856064 5082864 3007920
17:31:27 27919 8192000000 10138270768 10543325184 1142012736 12.234 89 22 44 0 1151890000 5153664 4723600
17:32:56 10089 8192000000 10161072112 10558734336 1241663872 13.162 98 28 56 1 1254125312 5886336 6575104
17:34:07 8243 8192000000 10166655520 10564513792 1257541104 13.307 99 35 70 0 1271465360 4737232 9187024
17:35:16 70024 8192000000 10188819808 10597584896 1291401168 13.617 100 27 54 0 1304257568 5607872 7248528
17:37:34 7601 8192000000 10199421264 10613837824 1304337712 13.735 100 35 68 2 1318596720 5816208 8442800
17:38:48 18426 8192000000 10241537968 10652012544 1339555408 14.053 100 43 85 1 1356368112 7422432 9390272
17:40:11 26148 8192000000 10268827232 10682740736 1355979248 14.201 100 54 106 0 1377001696 9356592 11665856
17:41:53 11473 8192000000 10287619552 10706784256 1378834736 14.406 100 55 110 0 1402842800 11423936 12584128
17:43:06 41979 8192000000 10279789024 10714275840 1377708688 14.396 100 30 60 0 1396033856 11218224 7106944
17:45:24 10684 8192000000 10302897824 10733125632 1392861744 14.531 100 31 60 1 1417999776 17683584 7454448
17:46:37 9624 8192000000 10341666736 10764865536 1409472224 14.679 100 57 114 1 1449543072 26309456 13761392
17:47:48 8629 8192000000 10339418400 10770157568 1417058048 14.747 100 49 98 1 1453527008 24017808 12451152
17:49:04 3369 8192000000 10360645072 10785837056 1422134608 14.792 100 66 130 1 1468416752 30374816 15907328
17:50:12 4582 8192000000 10355132560 10784043008 1427355552 14.838 100 59 119 3 1466787728 24934176 14498000
17:51:25 5721 8192000000 10369170832 10791030784 1428131984 14.845 100 76 149 1 1474488720 28901568 17455168
17:52:34 3369 8192000000 10366236400 10780180480 1419915584 14.772 100 86 170 1 1468945456 28347600 20682272
17:53:41 12039 8192000000 10342294608 10774142976 1413391632 14.714 100 35 70 1 1451290176 28151728 9746816
17:55:00 6274 8192000000 10342916672 10773925888 1413309968 14.713 100 35 69 0 1453999776 32040048 8649760
17:56:20 14089 8192000000 10318392048 10745569280 1369284208 14.321 98 44 85 2 1415070880 34352768 11433904
17:57:37 14611 8192000000 10313672576 10737795072 1347667152 14.126 97 47 95 2 1402171120 41372640 13131328
17:59:00 9995 8192000000 10337260944 10765340672 1383418016 14.447 99 37 74 0 1438349520 44855776 10075728
18:00:16 5933 8192000000 10343249680 10771410944 1386147856 14.471 99 46 91 0 1441287296 42796944 12342496
18:01:26 6669 8192000000 10314593792 10736562176 1372605856 14.350 99 49 96 1 1418690768 33264336 12820576
18:02:35 11326 8192000000 10334378304 10763452416 1378419744 14.402 99 35 70 1 1433133920 45074272 9639904
18:03:55 15624 8192000000 10331257360 10766929920 1385889952 14.469 100 27 54 1 1438861600 45215376 7756272
18:05:17 33459 8192000000 10328528560 10772926464 1370548480 14.332 99 24 48 0 1422142592 44609344 6984768
18:07:25 31929 8192000000 10309648128 10758791168 1337555200 14.035 99 17 34 1 1395616320 53045984 5015136
18:09:16 39192 8192000000 10281127984 10709958656 1352966432 14.174 99 42 83 3 1372687808 10146112 9575264
18:10:59 62436 8192000000 10299043664 10729385984 1361404032 14.250 99 54 106 1 1384385856 11745120 11236704
18:13:03 79880 8192000000 10304468656 10757877760 1392489456 14.528 100 23 46 1 1412552880 14628384 5435040
18:15:23 81739 8192000000 10315001392 10770350080 1386952064 14.479 99 19 39 1 1409861648 18518256 4391328
18:18:27 70386 8192000000 10304514640 10755878912 1384725248 14.459 99 28 57 1 1406885712 15732400 6428064
18:20:38 79322 8192000000 10310906736 10768986112 1406190272 14.650 100 22 44 0 1423384144 12084384 5109488
18:22:59 108898 8192000000 10299602752 10759630848 1391823616 14.522 99 33 67 1 1407743104 8558032 7361456
18:25:56 86520 8192000000 10315934544 10779811840 1391069184 14.515 98 27 54 0 1409614800 11870864 6674752
18:28:23 30053 8192000000 10329941680 10786836480 1414248320 14.722 99 42 84 1 1440446400 16134416 10063664
18:30:07 40741 8192000000 10327795376 10795847680 1418957824 14.763 100 18 36 0 1447287248 23505296 4824128
18:31:48 41277 8192000000 10300497184 10777927680 1397552288 14.573 99 15 32 3 1425131392 23397792 4181312
18:33:50 71670 8192000000 10313895536 10787676160 1397772352 14.575 99 12 24 0 1421792336 20980112 3039872
18:36:49 37880 8192000000 10310985184 10774179840 1398446752 14.581 99 22 44 3 1415918208 12392416 5079040
18:38:28 62869 8192000000 10325964592 10787471360 1404214960 14.633 99 25 49 3 1426873168 17303472 5354736
18:40:36 79838 8192000000 10298811520 10767732736 1351320032 14.159 96 9 17 1 1366432480 13361232 1751216
18:42:58 91497 8192000000 10256561408 10714714112 1284215104 13.551 93 12 24 0 1304597568 17867008 2515456
18:45:30 58564 8192000000 10297927584 10730582016 1357055760 14.211 99 38 76 0 1378611616 13162016 8393840
18:47:37 32249 8192000000 10272798464 10716860416 1341474352 14.071 98 34 69 1 1361900608 12657056 7769200
18:49:13 54703 8192000000 10275878144 10713616384 1332752976 13.992 98 24 48 0 1353077840 15602240 4722624
18:51:47 116887 8192000000 10245357312 10680655872 1264238800 13.369 93 29 58 0 1278942768 8829072 5874896
18:54:47 9312 8192000000 10236163920 10672832512 1333579392 13.999 100 13 26 0 1346447952 9444656 3423904
18:55:57 65722 8192000000 10246557696 10707972096 1316211392 13.842 97 12 24 0 1329420960 10483280 2726288
18:58:03 45898 8192000000 10246390976 10695213056 1336508480 14.026 99 12 24 1 1347324688 8161648 2654560
18:59:53 56678 8192000000 10250840112 10705158144 1352569200 14.171 100 17 34 0 1365194416 7976128 4649088
19:02:04 81863 8192000000 10244706896 10695319552 1322790000 13.902 97 10 20 0 1339054672 13841408 2423264
19:04:26 37434 8192000000 10254886704 10692169728 1333197120 13.996 98 36 72 0 1350927664 9883440 7847104
19:06:04 88114 8192000000 10273232864 10721144832 1351836672 14.164 99 11 22 0 1363607728 9585104 2185952
19:08:38 12019 8192000000 10296834608 10734825472 1368844016 14.317 99 34 69 2 1384177968 7660528 7673424
19:10:01 67359 8192000000 10299975648 10757062656 1373922368 14.362 99 17 34 1 1394301600 16567312 3811920
19:12:15 131963 8192000000 10308805040 10777837568 1384456992 14.456 99 9 18 0 1407898112 20935040 2506080
19:15:27 63836 8192000000 10298632432 10745466880 1363208672 14.266 98 17 34 0 1378037120 11161024 3667424
19:17:33 48579 8192000000 10303952896 10753101824 1385154080 14.463 99 30 60 0 1402769312 10111392 7503840
19:19:32 55069 8192000000 10304478320 10749935616 1374112928 14.364 98 31 61 0 1389037392 8621168 6303296
19:21:31 78950 8192000000 10283297744 10752200704 1385735104 14.468 99 13 26 1 1400805536 11903216 3167216
19:23:51 76038 8192000000 10261352960 10711396352 1326556704 13.936 95 21 42 0 1341484976 9750560 5177712
19:26:30 35251 8192000000 10274887088 10731917312 1368437440 14.313 99 10 20 0 1377518960 6654992 2426528
19:28:13 53163 8192000000 10237543024 10693156864 1326045440 13.931 97 20 40 0 1341210512 10437232 4727840
19:30:13 58871 8192000000 10262242160 10714832896 1312357296 13.807 96 17 34 1 1329430272 13245632 3827344
19:32:33 72998 8192000000 10256178304 10709454848 1280598192 13.518 94 14 28 0 1293827952 9920848 3308912
19:34:47 40475 8192000000 10262707344 10706399232 1345351120 14.106 99 28 56 1 1360597152 8423392 6822640
19:36:28 67675 8192000000 10273105392 10724679680 1354005280 14.183 99 27 53 1 1374216544 14494432 5716832

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-04-2022 19:39:16 to 21-04-2022 19:39:31.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 19:39:31 to 21-04-2022 19:39:33.

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          238740
MON$OLDEST_ACTIVE               239744
MON$OLDEST_SNAPSHOT             239744
MON$NEXT_TRANSACTION            239744
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                       2023424
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 ---
:1401: 
:1402: mscs-test-161.budzdorov.ru    Thu Apr 21 19:38:16 2022
:1403:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1404: 
:1405: 
:1406: mscs-test-161.budzdorov.ru    Thu Apr 21 19:38:23 2022
:1407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1408: 
--- end of diff output ---

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

21.04.22 19:39:34. 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-21 19:39:37 No severe PSQL-related problems occured
21.04.22 19:39:38. End of report.