Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2022 22: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 15396244 kB
MemAvailable 30542808 kB
Buffers 0 kB
Cached 16288492 kB
SwapCached 2072 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.11
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-10-01 22:38:33
DTS_END                         2022-10-01 22:38:33.3880
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42446
TRN_ID                          245916
ATT_ID                          693
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: 9187.75 20018 1102530
customer order: creation 182.33 343 21879
customer order: refuse 15.42 347 1850
order to supplier: creation 48.01 1359 5761
order to supplier: removal 3.56 103392 427
invoice (draft): creation 47.61 3360 5713
invoice (draft): removal 50.54 971 6065
invoice accept: apply 43.63 18506 5235
invoice accept: cancel 45.51 899 5461
customer reserve: creation 3999.63 186 479955
customer reserve: removal 4446.92 64 533630
realization accept: apply 87.53 168 10504
realization accept: cancel 79.92 123 9590
payment from customer: creation 63.14 116 7577
payment from customer: removal 6.09 73 731
payment to supplier: creation 51.91 147 6229
payment to supplier: removal 3.58 85 430
service: total inventory turnovers 4.66 1295 559
service: total monetary turnovers 6.90 49 828
service: aggregate perf. data 0.85 19733 102
service: refresh index statistics 0.03 249151 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-10-2022 22:39:17 to 01-10-2022 22:39:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3038
TEST_TIME 2 4003
TEST_TIME 3 4123
TEST_TIME 4 4502
TEST_TIME 5 4649
TEST_TIME 6 4348
TEST_TIME 7 4349
TEST_TIME 8 4358
TEST_TIME 9 4753
TEST_TIME 10 5038
TEST_TIME 11 5595
TEST_TIME 12 6070
TEST_TIME 13 6081
TEST_TIME 14 6116
TEST_TIME 15 6431
TEST_TIME 16 6807
TEST_TIME 17 7210
TEST_TIME 18 7700
TEST_TIME 19 7924
TEST_TIME 20 7886
TEST_TIME 21 8349
TEST_TIME 22 8375
TEST_TIME 23 8369
TEST_TIME 24 8567
TEST_TIME 25 8611
TEST_TIME 26 8664
TEST_TIME 27 8758
TEST_TIME 28 8857
TEST_TIME 29 8983
TEST_TIME 30 9069
TEST_TIME 31 9764
TEST_TIME 32 10430
TEST_TIME 33 10706
TEST_TIME 34 10550
TEST_TIME 35 10650
TEST_TIME 36 10542
TEST_TIME 37 10378
TEST_TIME 38 10272
TEST_TIME 39 10296
TEST_TIME 40 10162
TEST_TIME 41 10069
TEST_TIME 42 9947
TEST_TIME 43 9921
TEST_TIME 44 9910
TEST_TIME 45 9793
TEST_TIME 46 9664
TEST_TIME 47 9518
TEST_TIME 48 9499
TEST_TIME 49 9385
TEST_TIME 50 9478
TEST_TIME 51 9431
TEST_TIME 52 9314
TEST_TIME 53 9243
TEST_TIME 54 9166
TEST_TIME 55 9250
TEST_TIME 56 9133
TEST_TIME 57 9251
TEST_TIME 58 9333
TEST_TIME 59 9290
TEST_TIME 60 9298
TEST_TIME 61 9337
TEST_TIME 62 9376
TEST_TIME 63 9407
TEST_TIME 64 9404
TEST_TIME 65 9422
TEST_TIME 66 9442
TEST_TIME 67 9492
TEST_TIME 68 9458
TEST_TIME 69 9420
TEST_TIME 70 9574
TEST_TIME 71 9548
TEST_TIME 72 9579
TEST_TIME 73 9527
TEST_TIME 74 9443
TEST_TIME 75 9436
TEST_TIME 76 9415
TEST_TIME 77 9358
TEST_TIME 78 9329
TEST_TIME 79 9373
TEST_TIME 80 9366
TEST_TIME 81 9346
TEST_TIME 82 9261
TEST_TIME 83 9215
TEST_TIME 84 9173
TEST_TIME 85 9144
TEST_TIME 86 9148
TEST_TIME 87 9162
TEST_TIME 88 9194
TEST_TIME 89 9195
TEST_TIME 90 9269
TEST_TIME 91 9239
TEST_TIME 92 9261
TEST_TIME 93 9348
TEST_TIME 94 9342
TEST_TIME 95 9325
TEST_TIME 96 9346
TEST_TIME 97 9379
TEST_TIME 98 9395
TEST_TIME 99 9370
TEST_TIME 100 9395
TEST_TIME 101 9347
TEST_TIME 102 9308
TEST_TIME 103 9269
TEST_TIME 104 9248
TEST_TIME 105 9221
TEST_TIME 106 9171
TEST_TIME 107 9153
TEST_TIME 108 9166
TEST_TIME 109 9159
TEST_TIME 110 9163
TEST_TIME 111 9148
TEST_TIME 112 9142
TEST_TIME 113 9135
TEST_TIME 114 9128
TEST_TIME 115 9182
TEST_TIME 116 9179
TEST_TIME 117 9214
TEST_TIME 118 9193
TEST_TIME 119 9193
TEST_TIME 120 9197

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2022 22:39:21 to 01-10-2022 22:39:24.

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 1546014 1546014 0 0.00 0 16801 6 0 0
doc_list_biud 1546014 1546014 0 0.00 0 22272 29 0 0
sp_add_invoice_to_stock 5374 5235 139 2.58 35 170164 18506 0 139
sp_cancel_adding_invoice 5461 5461 0 0.00 4 77149 899 0 0
sp_cancel_client_order 1850 1850 0 0.00 4 12731 347 0 0
sp_cancel_customer_reserve 533630 533630 0 0.00 0 22274 65 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 2 2707 73 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 3970 85 0 0
sp_cancel_supplier_invoice 6077 6065 12 0.19 1 15265 971 0 12
sp_cancel_supplier_order 427 427 0 0.00 5 1085385 103393 0 0
sp_cancel_write_off 9590 9590 0 0.00 1 7338 124 0 0
sp_client_order 21879 21879 0 0.00 3 18199 344 0 0
sp_customer_reserve 479955 479955 0 0.00 0 91483 186 0 0
sp_fill_shopping_cart 33344 33344 0 0.00 1 18017 41 0 0
sp_fill_shopping_cart_clo_res 479943 479943 0 0.00 0 11866 13 0 0
sp_get_clo_for_invoice 5222 5222 0 0.00 0 16348 284 0 0
sp_kill_cost_storno 16212 16212 0 0.00 0 6986 61 0 0
sp_kill_qstorno_ret_qs2qd 1083342 1083342 0 0.00 0 22270 36 0 0
sp_kill_qty_storno 572749 572749 0 0.00 0 22272 77 0 0
sp_lock_selected_doc 21840 21840 0 0.00 0 7321 5 0 0
sp_make_cost_storno 29532 29532 0 0.00 0 16758 55 0 0
sp_multiply_rows_for_pdistr 29532 29532 0 0.00 0 9274 28 0 0
sp_multiply_rows_for_qdistr 21879 21879 0 0.00 0 10830 141 0 0
sp_pay_from_customer 7577 7577 0 0.00 4 18658 116 0 0
sp_pay_to_supplier 6229 6229 0 0.00 4 15882 147 0 0
sp_payment_common 13806 13806 0 0.00 1 18650 34 0 0
sp_qd_handle_on_cancel_clo 1850 1850 0 0.00 0 12722 323 0 0
sp_qd_handle_on_invoice_upd_sts 10683 10683 0 0.00 0 17788 341 0 0
sp_qd_handle_on_reserve_upd_sts 10504 10504 0 0.00 0 12697 58 0 0
sp_reserve_write_off 10504 10504 0 0.00 6 14067 168 0 0
sp_supplier_invoice 5713 5713 0 0.00 5 81023 3361 0 0
sp_supplier_order 5761 5761 0 0.00 5 52437 1359 0 0
srv_aggregate_perf_data 102 102 0 0.00 2674 104808 19734 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1835 108285 38786 0 0
srv_find_qd_qs_mism 1051904 1051904 0 0.00 0 14549 6 0 0
srv_make_invnt_saldo 559 559 0 0.00 33 66094 1296 0 0
srv_make_money_saldo 828 828 0 0.00 1 6011 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 149419 330058 249152 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 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 5235 5235 0 0.00 0 13939 152 0 0
t$perf-norm:sp_cancel_adding_invoice 456 456 0 0.00 0 7268 124 0 0
t$perf-norm:sp_cancel_client_order 1850 1850 0 0.00 0 2177 13 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 1987 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 6046 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 6273 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 858 16 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 34825 1715 0 0
t$perf-norm:sp_cancel_write_off 1058 1058 0 0.00 0 2199 11 0 0
t$perf-norm:sp_client_order 21879 21879 0 0.00 0 13213 16 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 2033 11 0 0
t$perf-norm:sp_pay_from_customer 7577 7577 0 0.00 0 5310 9 0 0
t$perf-norm:sp_pay_to_supplier 6229 6229 0 0.00 0 7528 11 0 0
t$perf-norm:sp_reserve_write_off 10504 10504 0 0.00 0 6236 14 0 0
t$perf-norm:sp_supplier_invoice 5713 5713 0 0.00 0 4080 16 0 0
t$perf-norm:sp_supplier_order 5761 5761 0 0.00 0 2551 13 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 208 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 114 4 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1371 22 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1699 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10722 10444 278 2.59 0 2815 16 0 278
v_all_customers 39732 39732 0 0.00 0 31 1 0 0
v_all_suppliers 32070 32070 0 0.00 0 30 1 0 0
v_all_wares 43758 43758 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 912 912 0 0.00 0 149 6 0 0
v_cancel_client_order 3700 3700 0 0.00 0 2665 4 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 2700 9 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 1992 7 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3919 10 0 0
v_cancel_supplier_invoice 920 896 24 2.60 0 1153 16 0 24
v_cancel_supplier_order 854 854 0 0.00 0 438 4 0 0
v_cancel_write_off 2116 2116 0 0.00 0 1155 12 0 0
v_max_id_clo_ord 5761 5761 0 0.00 0 9573 3 0 0
v_max_id_clo_res 991 991 0 0.00 0 4281 25 0 0
v_max_id_ord_sup 5704 5704 0 0.00 0 4315 5 0 0
v_max_non_paid_invoice 1659 1659 0 0.00 0 2497 4 0 0
v_max_non_paid_realizn 3986 3986 0 0.00 0 8386 8 0 0
v_min_id_clo_ord 5761 5761 0 0.00 0 4169 8 0 0
v_min_id_clo_res 991 991 0 0.00 0 4866 8 0 0
v_min_id_ord_sup 5704 5704 0 0.00 0 4929 6 0 0
v_min_non_paid_invoice 6229 6229 0 0.00 0 8113 26 0 0
v_min_non_paid_realizn 7577 7577 0 0.00 0 18645 27 0 0
v_our_firm 11208 11208 0 0.00 0 22 1 0 0
v_reserve_write_off 21008 21008 0 0.00 0 3916 5 0 0
x_lock_depdocs_on_canc_invoice 11078 11078 0 0.00 0 12855 132 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 3200 355 0 0
x_make_qstorno_1000_1200 5761 5761 0 0.00 1 52412 1265 0 0
x_make_qstorno_1200_2000 5713 5713 0 0.00 2 79476 3216 0 0
x_make_qstorno_2100_3300 479955 479955 0 0.00 0 91481 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2022 22:39:25 to 01-10-2022 22:39:29.

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:37 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:38 24046 8192000000 9905627488 10218799104 990963712 10.791 100 87 174 0 1019794544 6098992 22731840
20:11:12 16038 8192000000 9962384304 10285883392 1040520128 11.270 100 89 176 0 1071318720 8748448 22050144
20:12:33 15669 8192000000 9989010480 10323247104 1069398208 11.546 100 89 176 1 1101882064 10846080 21637776
20:13:55 11688 8192000000 10030626976 10375090176 1114251120 11.973 100 90 178 0 1147866768 12490928 21124720
20:15:12 8122 8192000000 10054474080 10412253184 1143496608 12.248 100 70 141 0 1170854496 11247584 16110304
20:16:26 3388 8192000000 10108955344 10471067648 1187883680 12.664 100 78 155 0 1217991136 12331584 17775872
20:17:31 1964 8192000000 10141195552 10513698816 1231554544 13.068 100 80 163 1 1262022720 12750480 17717696
20:18:35 9093 8192000000 10171146528 10554265600 1261409856 13.343 100 73 142 1 1289604496 12587680 15606960
20:19:46 1212 8192000000 10209553232 10591432704 1292502928 13.627 100 93 187 1 1326795552 12779696 21512928
20:20:49 4409 8192000000 10210963728 10604961792 1305220016 13.743 100 61 121 1 1327239552 7522416 14497120
20:21:56 33413 8192000000 10199872256 10614972416 1313160016 13.815 100 36 72 0 1327925072 5144928 9620128
20:23:33 10571 8192000000 10202010448 10623950848 1324690896 13.919 100 18 37 1 1334178800 5817552 3670352
20:24:44 9761 8192000000 10206104816 10632187904 1324992864 13.922 100 22 45 1 1336580160 6118416 5468880
20:25:56 8576 8192000000 10219142432 10643365888 1334769216 14.010 100 17 34 1 1344385344 6082192 3533936
20:27:07 24802 8192000000 10252980528 10659094528 1339468640 14.053 100 63 122 1 1361198640 6444768 15285232
20:28:32 6900 8192000000 10224887536 10639826944 1319202960 13.869 99 39 76 2 1334947376 6692000 9052416
20:29:39 4903 8192000000 10196513856 10609410048 1296015856 13.659 99 24 46 0 1307435936 6531888 4888192
20:30:47 17647 8192000000 10145464096 10548203520 1184860080 12.636 92 16 32 0 1193905232 5555168 3489984
20:32:08 35944 8192000000 10098886672 10490310656 1097127744 11.810 87 18 37 0 1105875552 4870336 3877472
20:33:48 5423 8192000000 10141830544 10545700864 1240786400 13.153 98 25 51 0 1252204624 5770896 5647328
20:34:57 8113 8192000000 10184058784 10584281088 1280822288 13.521 100 26 51 0 1296791856 10173552 5796016
20:36:07 73518 8192000000 10183864096 10602696704 1300632656 13.701 100 32 63 1 1313268320 5296864 7338800
20:38:22 7071 8192000000 10214030432 10628624384 1323059904 13.904 100 27 54 1 1334251936 5462144 5729888
20:39:39 13196 8192000000 10246058480 10657447936 1340957296 14.066 100 37 75 0 1358317424 9106720 8253408
20:41:03 11677 8192000000 10270397008 10685886464 1362178656 14.257 100 51 102 1 1385638016 11877712 11581648
20:42:36 20113 8192000000 10286964576 10706780160 1380235008 14.419 100 46 92 2 1407781808 16175600 11371200
20:44:01 44913 8192000000 10293579200 10726797312 1385319600 14.464 100 29 56 1 1413549856 21350688 6879568
20:45:50 5962 8192000000 10311731280 10732732416 1393071072 14.533 100 56 112 0 1425474720 18895008 13508640
20:47:01 19566 8192000000 10322596304 10743488512 1387774720 14.486 99 55 110 1 1425204624 24136032 13293872
20:48:24 6178 8192000000 10330042784 10746531840 1399630032 14.592 100 71 137 0 1443067664 27136080 16301552
20:49:36 2860 8192000000 10349606384 10765488128 1410213584 14.686 100 72 139 1 1457912768 30699168 17000016
20:50:44 6460 8192000000 10336691536 10764455936 1410174800 14.685 100 51 102 1 1449555168 26462320 12918048
20:51:56 14081 8192000000 10340231168 10760282112 1396004112 14.559 99 61 122 1 1437182768 26034304 15144352
20:53:14 7268 8192000000 10349115888 10771881984 1406378528 14.652 100 66 132 1 1455834672 33434032 16022112
20:54:28 3876 8192000000 10356709824 10773954560 1391255856 14.517 99 72 144 1 1448867024 39718720 17892448
20:55:35 19252 8192000000 10301435856 10723979264 1342383280 14.079 96 41 79 0 1385289520 32665520 10240720
20:56:58 29830 8192000000 10328982128 10758893568 1379139968 14.409 99 28 56 0 1423963552 37099024 7724560
20:58:33 1833 8192000000 10315097456 10733162496 1369142192 14.319 100 58 115 0 1419551472 36674976 13734304
20:59:46 2089 8192000000 10342079296 10748542976 1382808176 14.442 100 87 170 0 1435240512 33096192 19336144
21:00:52 1954 8192000000 10338076400 10753945600 1393215776 14.535 100 80 158 3 1445397648 32707760 19474112
21:02:04 2273 8192000000 10357493968 10769424384 1402408560 14.616 100 77 154 0 1462694960 39768912 20517488
21:03:12 11479 8192000000 10350406416 10773786624 1404501152 14.635 100 57 113 2 1459508544 40183584 14823808
21:04:39 5050 8192000000 10343702720 10764836864 1383927344 14.452 99 44 88 0 1435527072 38756944 12842784
21:05:48 8273 8192000000 10364817712 10789974016 1399016208 14.586 99 46 92 1 1460563360 48472448 13074704
21:07:05 27852 8192000000 10330307488 10769403904 1381193520 14.427 99 29 58 0 1440053760 50234720 8625520
21:08:50 20869 8192000000 10339248016 10772639744 1373053504 14.354 100 26 51 0 1439910672 58882848 7974320
21:10:14 8656 8192000000 10329013072 10756800512 1369337600 14.321 100 40 80 1 1432274736 52298720 10638416
21:11:25 17868 8192000000 10293672400 10736373760 1373694160 14.360 99 20 38 0 1392981488 14225600 5061728
21:12:43 27853 8192000000 10304905872 10739793920 1372388416 14.348 99 53 103 1 1395603312 10656752 12558144
21:14:23 45649 8192000000 10294069872 10752409600 1364776784 14.280 98 30 60 1 1386007088 13839424 7390880
21:16:16 30214 8192000000 10310077216 10752774144 1387291600 14.482 100 42 82 0 1412089408 15213888 9583920
21:18:07 80120 8192000000 10280020816 10741596160 1373127136 14.355 99 11 23 0 1386019664 10908752 1983776
21:20:30 64018 8192000000 10278589168 10743451648 1387228128 14.481 100 13 28 0 1399308096 9009856 3070112
21:22:35 52854 8192000000 10290952400 10747461632 1374116928 14.364 99 14 28 0 1386527104 9516304 2893872
21:25:25 77627 8192000000 10284747600 10750832640 1365535360 14.287 98 15 30 0 1379636672 10630848 3470464
21:27:52 38375 8192000000 10310778752 10764701696 1398364704 14.580 100 36 72 1 1414272400 8097584 7810112
21:29:37 47895 8192000000 10305189312 10772238336 1396473376 14.564 99 20 37 1 1414842608 14352528 4016704
21:31:31 37133 8192000000 10323282944 10775134208 1406533648 14.653 100 31 62 0 1428279200 13405232 8340320
21:33:12 39483 8192000000 10289567920 10755162112 1373580208 14.359 99 21 42 0 1397964224 18950032 5433984
21:34:58 47662 8192000000 10286829248 10763550720 1373920112 14.362 99 5 10 0 1391400000 16254736 1225152
21:36:46 62404 8192000000 10285833056 10745757696 1337089136 14.031 96 22 44 0 1353918704 11990992 4838576
21:39:22 71986 8192000000 10297341024 10755493888 1344780112 14.100 96 24 48 0 1358323744 8646848 4896784
21:41:37 35514 8192000000 10297084976 10754539520 1364412080 14.277 98 17 34 0 1379641600 11396016 3833504
21:43:19 36447 8192000000 10282144256 10731216896 1323510752 13.908 96 18 36 0 1337887488 10606240 3770496
21:44:59 83953 8192000000 10278059888 10729553920 1328566256 13.954 97 17 34 0 1342660736 10636016 3458464
21:47:24 21043 8192000000 10288003184 10732351488 1357393072 14.214 99 35 70 0 1378894496 13879824 7621600
21:48:47 61657 8192000000 10278954000 10721210368 1338818016 14.047 98 11 21 1 1357223024 16437472 1967536
21:50:52 75867 8192000000 10268642832 10725482496 1366170480 14.293 100 18 36 0 1379140624 8979472 3990672
21:53:45 67115 8192000000 10264393168 10707660800 1302025584 13.714 95 26 52 1 1314196336 6553984 5616768
21:55:54 20823 8192000000 10290421952 10718576640 1342441792 14.079 98 45 89 0 1362124224 9292720 10389712
21:57:19 46045 8192000000 10248970432 10707963904 1329672080 13.964 98 21 42 0 1345733344 11510320 4550944
21:59:45 38278 8192000000 10240929808 10696298496 1299804224 13.693 95 1 2 0 1305080160 5233584 42352
22:01:24 71579 8192000000 10276793152 10723057664 1368577648 14.314 100 16 32 0 1378343104 6509792 3255664
22:03:38 66046 8192000000 10291832816 10756853760 1369786160 14.325 99 23 46 0 1391228272 15701744 5740368
22:05:54 53802 8192000000 10284852336 10752860160 1357676896 14.216 98 12 24 0 1374155600 13403040 3075664
22:07:51 62924 8192000000 10302800704 10767081472 1374444144 14.367 99 21 42 0 1405472688 25772560 5255984
22:09:57 91392 8192000000 10297592112 10755928064 1386098928 14.471 99 20 39 0 1407926304 17500928 4326448
22:12:29 29223 8192000000 10294424016 10744680448 1371131040 14.337 98 21 42 0 1384023264 8680112 4212112
22:14:02 84149 8192000000 10306085568 10774081536 1384323104 14.455 99 32 64 0 1408997376 17350192 7324080
22:16:29 50932 8192000000 10298379408 10760699904 1382171536 14.436 99 19 38 0 1396365616 10359200 3834880
22:18:23 108285 8192000000 10282671120 10749870080 1348528016 14.134 97 21 42 0 1368279264 15156416 4594832
22:21:48 45076 8192000000 10264141392 10730377216 1349006272 14.139 98 18 34 0 1361581936 8392656 4183008
22:23:38 61906 8192000000 10278489456 10747486208 1370285024 14.330 99 12 24 0 1382718240 9542576 2890640
22:25:45 66447 8192000000 10269547632 10733764608 1350540976 14.152 98 18 36 0 1363894880 8999392 4354512
22:27:52 91773 8192000000 10253226880 10712621056 1255176880 13.286 91 17 34 0 1267828336 8779744 3871712
22:30:29 23927 8192000000 10270401232 10721689600 1348168080 14.131 98 19 38 0 1358413920 6140368 4105472
22:31:55 47788 8192000000 10274195712 10716532736 1315420256 13.835 96 22 42 0 1334259312 13742240 5096816
22:33:43 63148 8192000000 10270680064 10724253696 1331045808 13.977 97 19 38 0 1346208096 10827600 4334688
22:35:54 42873 8192000000 10277068704 10729664512 1341136624 14.068 98 17 34 0 1355571024 11102864 3331536
22:37:40 13876 8192000000 10268141920 10703302656 1337128128 14.032 99 55 110 0 1362062784 12154192 12780464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-10-2022 22:39:31 to 01-10-2022 22:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 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 01-10-2022 22:39:48 to 01-10-2022 22:39:50.

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          244277
MON$OLDEST_ACTIVE               246032
MON$OLDEST_SNAPSHOT             246032
MON$NEXT_TRANSACTION            246032
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                       2046048
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:37 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Sat Oct  1 22:38:45 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:67: 
--- end of diff output ---

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

01.10.22 22:39:51. 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-10-01 22:39:54 No severe PSQL-related problems occured
01.10.22 22:39:55. End of report.