Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.11.2021 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 16835128 kB
MemAvailable 31504744 kB
Buffers 0 kB
Cached 14814020 kB
SwapCached 0 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.8
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 2021-11-29 22:37:58
DTS_END                         2021-11-29 22:37:58.4660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43854
TRN_ID                          243580
ATT_ID                          696
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: 9307.84 19415 1116941
customer order: creation 174.95 358 20994
customer order: refuse 15.14 322 1817
order to supplier: creation 46.55 1226 5586
order to supplier: removal 3.31 96827 397
invoice (draft): creation 47.13 3553 5656
invoice (draft): removal 51.23 790 6148
invoice accept: apply 42.78 17610 5134
invoice accept: cancel 46.87 793 5624
customer reserve: creation 3969.59 172 476351
customer reserve: removal 4610.59 54 553271
realization accept: apply 85.61 157 10273
realization accept: cancel 81.54 109 9785
payment from customer: creation 60.65 113 7278
payment from customer: removal 5.93 74 712
payment to supplier: creation 49.67 123 5960
payment to supplier: removal 3.51 50 421
service: total inventory turnovers 4.92 1174 590
service: total monetary turnovers 6.94 32 833
service: aggregate perf. data 0.89 21983 107
service: refresh index statistics 0.03 242783 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-11-2021 22:38:57 to 29-11-2021 22:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 976
TEST_TIME 2 4168
TEST_TIME 3 5502
TEST_TIME 4 5163
TEST_TIME 5 5684
TEST_TIME 6 5786
TEST_TIME 7 5652
TEST_TIME 8 5697
TEST_TIME 9 5440
TEST_TIME 10 5710
TEST_TIME 11 6019
TEST_TIME 12 6018
TEST_TIME 13 6214
TEST_TIME 14 6380
TEST_TIME 15 6474
TEST_TIME 16 6997
TEST_TIME 17 7308
TEST_TIME 18 7693
TEST_TIME 19 8405
TEST_TIME 20 8791
TEST_TIME 21 9036
TEST_TIME 22 9028
TEST_TIME 23 9115
TEST_TIME 24 9798
TEST_TIME 25 10114
TEST_TIME 26 10917
TEST_TIME 27 10651
TEST_TIME 28 10425
TEST_TIME 29 10269
TEST_TIME 30 10275
TEST_TIME 31 10251
TEST_TIME 32 10395
TEST_TIME 33 10288
TEST_TIME 34 10133
TEST_TIME 35 9992
TEST_TIME 36 10021
TEST_TIME 37 10148
TEST_TIME 38 10030
TEST_TIME 39 9870
TEST_TIME 40 9831
TEST_TIME 41 9801
TEST_TIME 42 9803
TEST_TIME 43 9627
TEST_TIME 44 9691
TEST_TIME 45 9546
TEST_TIME 46 9463
TEST_TIME 47 9394
TEST_TIME 48 9284
TEST_TIME 49 9205
TEST_TIME 50 9163
TEST_TIME 51 9171
TEST_TIME 52 9249
TEST_TIME 53 9403
TEST_TIME 54 9356
TEST_TIME 55 9366
TEST_TIME 56 9525
TEST_TIME 57 9464
TEST_TIME 58 9513
TEST_TIME 59 9544
TEST_TIME 60 9637
TEST_TIME 61 9643
TEST_TIME 62 9808
TEST_TIME 63 9672
TEST_TIME 64 9590
TEST_TIME 65 9570
TEST_TIME 66 9503
TEST_TIME 67 9524
TEST_TIME 68 9503
TEST_TIME 69 9442
TEST_TIME 70 9495
TEST_TIME 71 9495
TEST_TIME 72 9451
TEST_TIME 73 9491
TEST_TIME 74 9468
TEST_TIME 75 9476
TEST_TIME 76 9452
TEST_TIME 77 9562
TEST_TIME 78 9575
TEST_TIME 79 9556
TEST_TIME 80 9683
TEST_TIME 81 9570
TEST_TIME 82 9474
TEST_TIME 83 9463
TEST_TIME 84 9419
TEST_TIME 85 9346
TEST_TIME 86 9331
TEST_TIME 87 9260
TEST_TIME 88 9317
TEST_TIME 89 9306
TEST_TIME 90 9354
TEST_TIME 91 9387
TEST_TIME 92 9358
TEST_TIME 93 9363
TEST_TIME 94 9398
TEST_TIME 95 9379
TEST_TIME 96 9428
TEST_TIME 97 9469
TEST_TIME 98 9556
TEST_TIME 99 9527
TEST_TIME 100 9500
TEST_TIME 101 9522
TEST_TIME 102 9493
TEST_TIME 103 9507
TEST_TIME 104 9435
TEST_TIME 105 9449
TEST_TIME 106 9406
TEST_TIME 107 9371
TEST_TIME 108 9310
TEST_TIME 109 9308
TEST_TIME 110 9312
TEST_TIME 111 9284
TEST_TIME 112 9277
TEST_TIME 113 9265
TEST_TIME 114 9259
TEST_TIME 115 9230
TEST_TIME 116 9239
TEST_TIME 117 9272
TEST_TIME 118 9346
TEST_TIME 119 9319
TEST_TIME 120 9311

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-11-2021 22:39:01 to 29-11-2021 22:39:04.

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 1556354 1556354 0 0.00 0 23892 6 0 0
doc_list_biud 1556354 1556354 0 0.00 0 27655 25 0 0
sp_add_invoice_to_stock 5238 5134 104 1.98 51 185836 17610 0 104
sp_cancel_adding_invoice 5624 5624 0 0.00 5 53040 794 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 22113 323 0 0
sp_cancel_customer_reserve 553271 553271 0 0.00 1 27076 55 0 0
sp_cancel_pay_from_customer 712 712 0 0.00 3 7465 75 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 1275 50 0 0
sp_cancel_supplier_invoice 6154 6148 6 0.09 2 16442 790 0 6
sp_cancel_supplier_order 397 397 0 0.00 5 1156138 96827 0 0
sp_cancel_write_off 9785 9785 0 0.00 1 11000 109 0 0
sp_client_order 20994 20994 0 0.00 3 23334 359 0 0
sp_customer_reserve 476351 476351 0 0.00 0 88474 172 0 0
sp_fill_shopping_cart 32221 32221 0 0.00 1 16314 42 0 0
sp_fill_shopping_cart_clo_res 476342 476342 0 0.00 0 23757 15 0 0
sp_get_clo_for_invoice 5121 5121 0 0.00 0 48294 419 0 0
sp_kill_cost_storno 16542 16542 0 0.00 0 10433 53 0 0
sp_kill_qstorno_ret_qs2qd 1122872 1122872 0 0.00 0 27068 30 0 0
sp_kill_qty_storno 592433 592433 0 0.00 0 27652 65 0 0
sp_lock_selected_doc 21332 21332 0 0.00 0 7460 5 0 0
sp_make_cost_storno 28629 28629 0 0.00 0 23881 51 0 0
sp_multiply_rows_for_pdistr 28629 28629 0 0.00 0 9937 29 0 0
sp_multiply_rows_for_qdistr 20994 20994 0 0.00 0 16388 180 0 0
sp_pay_from_customer 7278 7278 0 0.00 4 16260 113 0 0
sp_pay_to_supplier 5960 5960 0 0.00 4 13745 123 0 0
sp_payment_common 13238 13238 0 0.00 1 16196 27 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 22088 295 0 0
sp_qd_handle_on_invoice_upd_sts 10742 10742 0 0.00 0 27646 313 0 0
sp_qd_handle_on_reserve_upd_sts 10273 10273 0 0.00 0 18410 51 0 0
sp_reserve_write_off 10273 10273 0 0.00 6 18431 158 0 0
sp_supplier_invoice 5656 5656 0 0.00 6 85156 3554 0 0
sp_supplier_order 5586 5586 0 0.00 4 39005 1227 0 0
srv_aggregate_perf_data 107 107 0 0.00 1894 731302 21983 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1532 132101 48172 0 0
srv_find_qd_qs_mism 1067217 1067217 0 0.00 0 15762 5 0 0
srv_make_invnt_saldo 590 590 0 0.00 41 123180 1174 0 0
srv_make_money_saldo 833 833 0 0.00 0 5183 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 105236 384535 242784 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5134 5134 0 0.00 0 11258 147 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 0 5263 103 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 7302 15 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 3938 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 712 712 0 0.00 0 2499 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 473 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1058 11 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 21770 1425 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 4890 14 0 0
t$perf-norm:sp_client_order 20994 20994 0 0.00 0 11253 19 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 2365 8 0 0
t$perf-norm:sp_pay_from_customer 7278 7278 0 0.00 0 6310 11 0 0
t$perf-norm:sp_pay_to_supplier 5960 5960 0 0.00 0 4149 7 0 0
t$perf-norm:sp_reserve_write_off 10273 10273 0 0.00 0 5833 12 0 0
t$perf-norm:sp_supplier_invoice 5656 5656 0 0.00 0 5503 18 0 0
t$perf-norm:sp_supplier_order 5586 5586 0 0.00 0 5355 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 103 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 590 590 0 0.00 0 1720 21 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1042 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10444 10236 208 1.99 0 3685 15 0 208
v_all_customers 38266 38266 0 0.00 0 20 0 0 0
v_all_suppliers 31274 31274 0 0.00 0 21 1 0 0
v_all_wares 41988 41988 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 2096 12 0 0
v_cancel_client_order 3634 3634 0 0.00 0 2333 6 0 0
v_cancel_customer_prepayment 1424 1424 0 0.00 0 930 4 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1216 4 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 432 3 0 0
v_cancel_supplier_invoice 840 828 12 1.42 0 1117 13 0 12
v_cancel_supplier_order 794 794 0 0.00 0 1471 7 0 0
v_cancel_write_off 2054 2054 0 0.00 0 6159 14 0 0
v_max_id_clo_ord 5586 5586 0 0.00 0 16272 5 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 2504 11 0 0
v_max_id_ord_sup 5641 5641 0 0.00 0 5527 7 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 516 3 0 0
v_max_non_paid_realizn 3856 3856 0 0.00 0 3321 6 0 0
v_min_id_clo_ord 5586 5586 0 0.00 0 6552 8 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 21293 36 0 0
v_min_id_ord_sup 5641 5641 0 0.00 0 5999 7 0 0
v_min_non_paid_invoice 5960 5960 0 0.00 0 13720 22 0 0
v_min_non_paid_realizn 7278 7278 0 0.00 0 16191 20 0 0
v_our_firm 10566 10566 0 0.00 0 14 1 0 0
v_reserve_write_off 20546 20546 0 0.00 0 3685 5 0 0
x_lock_depdocs_on_canc_invoice 11358 11358 0 0.00 0 13360 102 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 7115 334 0 0
x_make_qstorno_1000_1200 5586 5586 0 0.00 1 38978 1145 0 0
x_make_qstorno_1200_2000 5656 5656 0 0.00 3 84752 3389 0 0
x_make_qstorno_2100_3300 476351 476351 0 0.00 0 88474 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-11-2021 22:39:05 to 29-11-2021 22:39:09.

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:02 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:03 29911 8192000000 9909509488 10223271936 986480832 10.747 100 88 175 2 1015237888 6192576 22564480
20:10:38 4733 8192000000 9948778000 10274611200 1035672944 11.223 100 87 174 1 1066829536 8863792 22292800
20:11:46 11939 8192000000 9991681344 10325442560 1074581792 11.596 100 90 180 0 1107361664 9924128 22855744
20:13:03 16945 8192000000 10024104064 10368839680 1109301552 11.926 100 80 161 0 1139927760 10870080 19756128
20:14:26 11083 8192000000 10071285120 10429308928 1157715616 12.382 100 73 146 0 1186445360 11480480 17249264
20:15:39 6270 8192000000 10117858848 10484711424 1203687520 12.811 100 84 167 2 1235510848 13440640 18382688
20:16:50 2859 8192000000 10149344592 10525941760 1240129200 13.147 100 70 140 1 1267723120 12504928 15088992
20:17:54 3270 8192000000 10182881408 10565304320 1264608096 13.372 100 87 174 2 1298524416 14722896 19193424
20:18:59 1949 8192000000 10208571344 10593759232 1297578736 13.673 100 87 171 2 1331249632 14890848 18780048
20:20:02 1885 8192000000 10203142048 10601177088 1308002944 13.768 100 58 116 0 1328927120 7737984 13186192
20:21:05 9261 8192000000 10187209808 10605834240 1309588720 13.782 100 21 42 2 1320634304 6311824 4733760
20:22:17 17000 8192000000 10208380256 10618998784 1316836800 13.848 100 17 34 0 1326861664 5909264 4115600
20:23:35 29278 8192000000 10211117472 10633383936 1325853456 13.930 100 28 56 1 1337523552 5445008 6225088
20:25:05 5436 8192000000 10220061168 10640351232 1328266624 13.951 100 25 51 0 1339726144 6211024 5248496
20:26:11 6437 8192000000 10219570096 10638794752 1327968944 13.949 100 13 26 0 1335910720 5138464 2803312
20:27:18 11300 8192000000 10206530000 10619191296 1283928032 13.549 97 18 36 0 1294371648 5601248 4842368
20:28:30 26417 8192000000 10196184224 10616418304 1277578320 13.491 97 21 42 0 1287744480 4963680 5202480
20:29:58 6742 8192000000 10129637344 10523668480 1213253088 12.899 96 30 60 1 1226246032 5754992 7237952
20:31:09 8537 8192000000 10108151200 10507304960 1189301312 12.677 96 12 24 1 1197346768 5190096 2855360
20:32:18 65283 8192000000 10158415056 10568245248 1249233744 13.231 98 32 63 2 1262045552 5724992 7086816
20:34:27 11207 8192000000 10185037920 10597720064 1283617872 13.546 99 24 48 1 1294986976 5864560 5504544
20:35:42 5888 8192000000 10194553968 10612203520 1304210864 13.734 100 20 39 0 1314436064 5606752 4618448
20:36:50 14348 8192000000 10207133168 10619707392 1311623632 13.801 100 31 63 2 1324707856 5842320 7241904
20:38:10 58277 8192000000 10238964368 10649538560 1326798560 13.938 100 45 90 1 1343547488 7437216 9311712
20:40:13 10540 8192000000 10276432240 10686062592 1357064608 14.211 100 65 131 1 1388153888 16872800 14216480
20:41:30 11486 8192000000 10288954000 10703486976 1370466320 14.331 100 59 117 1 1404046512 20289264 13290928
20:42:45 6680 8192000000 10297090240 10713726976 1378467472 14.403 100 52 104 3 1411541936 21131184 11943280
20:44:00 15746 8192000000 10309924784 10733801472 1379713360 14.414 99 41 82 1 1416359200 26630672 10015168
20:45:31 12733 8192000000 10328366176 10753277952 1403385808 14.625 100 48 96 1 1441574016 26452752 11735456
20:46:50 13825 8192000000 10350977664 10777645056 1412712176 14.708 100 40 81 0 1461610144 38472512 10425456
20:48:10 2260 8192000000 10372491632 10790821888 1422872672 14.798 100 73 147 0 1480876528 38804512 19199344
20:49:16 5837 8192000000 10366628832 10782597120 1402985152 14.622 99 77 155 0 1463752832 41941248 18826432
20:50:28 4445 8192000000 10362366832 10781569024 1400455600 14.599 99 60 120 2 1460270592 44920608 14894384
20:51:39 4395 8192000000 10371177536 10779164672 1396764912 14.566 99 81 162 1 1464018464 47355200 19898352
20:52:48 4207 8192000000 10357615600 10766749696 1383995200 14.452 100 79 157 0 1460675856 56937840 19742816
20:54:00 5158 8192000000 10363521264 10779181056 1382662000 14.440 99 71 142 0 1459374224 58817920 17894304
20:55:09 4471 8192000000 10362392368 10779750400 1384270432 14.455 100 68 134 1 1466027360 64364096 17392832
20:56:20 9129 8192000000 10357486368 10782994432 1395350672 14.554 100 63 125 0 1467323888 56319584 15653632
20:57:36 1532 8192000000 10362153248 10778857472 1384405488 14.456 99 85 168 2 1460664288 55503472 20755328
20:58:43 17070 8192000000 10320565824 10759974912 1360140256 14.239 98 30 60 2 1410753904 42781680 7831968
21:00:13 12270 8192000000 10316982560 10752987136 1358818624 14.227 99 32 62 3 1414701888 47060256 8823008
21:01:34 30363 8192000000 10304345120 10752843776 1368004624 14.309 100 21 42 0 1428513040 54143264 6365152
21:03:06 23028 8192000000 10263244288 10707537920 1309258096 13.779 97 19 36 1 1347390224 33807728 4324400
21:04:39 126165 8192000000 10265123744 10690236416 1321930640 13.894 98 22 44 1 1334443408 7529712 4983056
21:07:53 42234 8192000000 10276739888 10720673792 1350151936 14.149 99 24 48 0 1373815360 18347680 5315744
21:09:41 132100 8192000000 10296016864 10741972992 1344352896 14.097 98 13 26 0 1363561584 16264768 2943920
21:12:55 6886 8192000000 10321916064 10751356928 1393758944 14.539 100 48 96 0 1416751040 11665952 11326144
21:14:06 48562 8192000000 10296856224 10759368704 1391643664 14.521 100 17 34 0 1411060704 15030416 4386624
21:15:55 55089 8192000000 10316005344 10768830464 1400956400 14.604 100 22 44 2 1421995840 15105824 5933616
21:17:54 33531 8192000000 10311947584 10767970304 1403114544 14.623 100 19 39 0 1427749008 19190688 5443776
21:21:03 46893 8192000000 10297288688 10766241792 1393201152 14.534 99 24 46 1 1411977280 13403376 5372752
21:22:51 45876 8192000000 10288090288 10743148544 1375906304 14.380 98 17 32 1 1386328352 6353808 4068240
21:24:39 63784 8192000000 10292854736 10750382080 1390941536 14.514 99 21 41 1 1401797216 6357392 4498288
21:27:11 51611 8192000000 10313164464 10777989120 1412200640 14.703 100 20 40 0 1431512416 14228880 5082896
21:29:03 74753 8192000000 10293169952 10749911040 1382860512 14.442 98 12 24 0 1402137664 16160304 3116848
21:31:22 49538 8192000000 10302459216 10762813440 1391269520 14.517 99 25 50 1 1409221776 12105728 5846528
21:33:14 89973 8192000000 10309367056 10757935104 1339935136 14.057 95 18 35 0 1354402208 10793712 3673360
21:35:49 15767 8192000000 10292370960 10728968192 1364456928 14.277 99 51 100 3 1386775312 11667120 10651264
21:37:06 43638 8192000000 10277888144 10734272512 1345283216 14.105 98 16 32 0 1364100688 15473584 3343888
21:38:50 114741 8192000000 10259231648 10711830528 1322261680 13.897 96 10 18 0 1344394512 20485216 1647616
21:41:51 61499 8192000000 10238963376 10695385088 1321759376 13.893 97 17 34 0 1334640640 9592544 3288720
21:45:05 67333 8192000000 10256848720 10701975552 1309154272 13.778 96 13 26 0 1322536288 10403136 2978880
21:47:24 90020 8192000000 10277878512 10723078144 1351502224 14.161 99 18 36 0 1366704240 10661328 4540688
21:49:54 93994 8192000000 10276975840 10731438080 1349574064 14.144 98 17 34 0 1362605424 9487664 3543696
21:52:35 32002 8192000000 10288981072 10727940096 1338312784 14.042 97 25 50 0 1352255888 8285712 5657392
21:54:11 66785 8192000000 10279551888 10743541760 1340317888 14.060 98 22 44 1 1365294960 19891328 5085744
21:56:54 86250 8192000000 10270163152 10739806208 1347493216 14.125 98 11 22 0 1364964320 15133040 2338064
21:59:53 50740 8192000000 10248414224 10700353536 1301921840 13.713 95 23 46 1 1313399104 6653680 4823584
22:01:50 52423 8192000000 10258133888 10716647424 1364134336 14.274 100 9 18 2 1376737136 10791376 1811424
22:03:43 49829 8192000000 10277245840 10736340992 1360771920 14.244 99 12 24 0 1376141008 12469104 2899984
22:06:22 68817 8192000000 10266592960 10722652160 1345834608 14.110 98 16 32 0 1369246208 19197376 4214224
22:08:34 101111 8192000000 10308016576 10752868352 1360780720 14.244 98 20 40 1 1373352928 8412736 4159472
22:11:17 54848 8192000000 10312090272 10763767808 1396422288 14.563 100 16 32 1 1410269136 10336832 3510016
22:13:12 74490 8192000000 10331217840 10782244864 1414292592 14.722 100 16 32 2 1433694896 16124192 3278112
22:15:32 74701 8192000000 10302876720 10762887168 1349849776 14.146 96 8 15 0 1362248624 10927504 1471344
22:17:50 59823 8192000000 10322286928 10769489920 1380990768 14.425 98 44 88 2 1403760144 13434208 9335168
22:19:51 78197 8192000000 10302437280 10765852672 1359465696 14.233 97 26 52 0 1383448256 18496976 5485584
22:22:11 60684 8192000000 10286150080 10740060160 1353728384 14.181 97 21 42 0 1366057776 7888688 4440704
22:24:16 82760 8192000000 10275658624 10728529920 1371779360 14.343 99 11 23 0 1381772064 7582944 2409760
22:26:40 51247 8192000000 10271203088 10721288192 1358718912 14.226 98 14 28 0 1371866064 10161968 2985184
22:28:32 54173 8192000000 10277234768 10729680896 1343435312 14.088 97 15 30 1 1353470928 7078368 2957248
22:30:26 53138 8192000000 10261007728 10716844032 1337858480 14.038 97 10 20 0 1349954464 9914816 2181168
22:33:15 71135 8192000000 10236776608 10683924480 1285937600 13.567 95 25 50 1 1303697040 12234912 5524528
22:35:26 107790 8192000000 10255383376 10698620928 1314694768 13.829 97 12 24 1 1327286080 10042320 2548992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-11-2021 22:39:11 to 29-11-2021 22:39:26.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-11-2021 22:39:27 to 29-11-2021 22:39:29.

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          242735
MON$OLDEST_ACTIVE               243706
MON$OLDEST_SNAPSHOT             243706
MON$NEXT_TRANSACTION            243706
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                       2016640
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:556: 
:557: mscs-test-161.budzdorov.ru    Mon Nov 29 22:38:06 2021
:558:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:559: 
:560: 
:561: mscs-test-161.budzdorov.ru    Mon Nov 29 22:38:06 2021
:562:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:563: 
:564: 
:565: mscs-test-161.budzdorov.ru    Mon Nov 29 22:38:06 2021
:566:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:567: 
:568: 
:569: mscs-test-161.budzdorov.ru    Mon Nov 29 22:38:06 2021
:570:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:571: 
--- end of diff output ---

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

29.11.21 22:39:30. 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
2021-11-29 22:39:32 No severe PSQL-related problems occured
29.11.21 22:39:33. End of report.