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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16211988 kB
MemAvailable 30878580 kB
Buffers 0 kB
Cached 15503108 kB
SwapCached 388 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-09-11 22:37:49
DTS_END                         2021-09-11 22:37:49.7260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38512
TRN_ID                          246239
ATT_ID                          716
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: 9202.96 17376 1104355
customer order: creation 181.00 351 21720
customer order: refuse 15.17 336 1820
order to supplier: creation 47.71 1306 5725
order to supplier: removal 3.38 101780 405
invoice (draft): creation 47.70 3332 5724
invoice (draft): removal 50.53 808 6064
invoice accept: apply 43.55 18228 5226
invoice accept: cancel 44.98 820 5397
customer reserve: creation 4011.60 181 481392
customer reserve: removal 4457.82 60 534938
realization accept: apply 88.90 166 10668
realization accept: cancel 76.59 114 9191
payment from customer: creation 61.63 101 7395
payment from customer: removal 5.44 70 653
payment to supplier: creation 51.14 125 6137
payment to supplier: removal 3.63 81 436
service: total inventory turnovers 5.05 1010 606
service: total monetary turnovers 6.41 33 769
service: aggregate perf. data 0.71 24669 85
service: refresh index statistics 0.03 193948 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-09-2021 22:39:22 to 11-09-2021 22:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3976
TEST_TIME 2 3840
TEST_TIME 3 4016
TEST_TIME 4 4838
TEST_TIME 5 5040
TEST_TIME 6 5777
TEST_TIME 7 5847
TEST_TIME 8 6362
TEST_TIME 9 6554
TEST_TIME 10 6498
TEST_TIME 11 6361
TEST_TIME 12 6645
TEST_TIME 13 6809
TEST_TIME 14 7076
TEST_TIME 15 7337
TEST_TIME 16 7662
TEST_TIME 17 7550
TEST_TIME 18 7547
TEST_TIME 19 7714
TEST_TIME 20 7998
TEST_TIME 21 8253
TEST_TIME 22 8461
TEST_TIME 23 8639
TEST_TIME 24 8555
TEST_TIME 25 8843
TEST_TIME 26 9064
TEST_TIME 27 9106
TEST_TIME 28 9660
TEST_TIME 29 10319
TEST_TIME 30 10215
TEST_TIME 31 10127
TEST_TIME 32 10210
TEST_TIME 33 10390
TEST_TIME 34 10358
TEST_TIME 35 10161
TEST_TIME 36 9904
TEST_TIME 37 9833
TEST_TIME 38 9959
TEST_TIME 39 9819
TEST_TIME 40 9804
TEST_TIME 41 9782
TEST_TIME 42 9696
TEST_TIME 43 9656
TEST_TIME 44 9721
TEST_TIME 45 9776
TEST_TIME 46 9746
TEST_TIME 47 9679
TEST_TIME 48 9613
TEST_TIME 49 9515
TEST_TIME 50 9520
TEST_TIME 51 9403
TEST_TIME 52 9343
TEST_TIME 53 9335
TEST_TIME 54 9300
TEST_TIME 55 9270
TEST_TIME 56 9252
TEST_TIME 57 9360
TEST_TIME 58 9343
TEST_TIME 59 9445
TEST_TIME 60 9427
TEST_TIME 61 9567
TEST_TIME 62 9571
TEST_TIME 63 9530
TEST_TIME 64 9559
TEST_TIME 65 9574
TEST_TIME 66 9585
TEST_TIME 67 9603
TEST_TIME 68 9586
TEST_TIME 69 9552
TEST_TIME 70 9469
TEST_TIME 71 9506
TEST_TIME 72 9419
TEST_TIME 73 9418
TEST_TIME 74 9379
TEST_TIME 75 9363
TEST_TIME 76 9336
TEST_TIME 77 9281
TEST_TIME 78 9316
TEST_TIME 79 9353
TEST_TIME 80 9338
TEST_TIME 81 9315
TEST_TIME 82 9390
TEST_TIME 83 9410
TEST_TIME 84 9427
TEST_TIME 85 9440
TEST_TIME 86 9469
TEST_TIME 87 9472
TEST_TIME 88 9517
TEST_TIME 89 9530
TEST_TIME 90 9590
TEST_TIME 91 9525
TEST_TIME 92 9462
TEST_TIME 93 9441
TEST_TIME 94 9411
TEST_TIME 95 9375
TEST_TIME 96 9332
TEST_TIME 97 9314
TEST_TIME 98 9252
TEST_TIME 99 9196
TEST_TIME 100 9230
TEST_TIME 101 9251
TEST_TIME 102 9218
TEST_TIME 103 9287
TEST_TIME 104 9323
TEST_TIME 105 9333
TEST_TIME 106 9399
TEST_TIME 107 9364
TEST_TIME 108 9342
TEST_TIME 109 9366
TEST_TIME 110 9367
TEST_TIME 111 9408
TEST_TIME 112 9439
TEST_TIME 113 9421
TEST_TIME 114 9369
TEST_TIME 115 9342
TEST_TIME 116 9298
TEST_TIME 117 9259
TEST_TIME 118 9236
TEST_TIME 119 9231
TEST_TIME 120 9214

Performance per minute, chart


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

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 1548778 1548778 0 0.00 0 20072 7 0 0
doc_list_biud 1548775 1548775 0 0.00 0 36681 26 0 0
sp_add_invoice_to_stock 5376 5226 150 2.79 46 245594 18228 0 150
sp_cancel_adding_invoice 5397 5397 0 0.00 4 50384 821 0 0
sp_cancel_client_order 1820 1820 0 0.00 4 7180 337 0 0
sp_cancel_customer_reserve 534938 534938 0 0.00 1 24201 60 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 4873 71 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 3266 81 0 0
sp_cancel_supplier_invoice 6077 6064 13 0.21 2 22003 808 0 13
sp_cancel_supplier_order 405 405 0 0.00 13 817154 101781 0 0
sp_cancel_write_off 9191 9191 0 0.00 1 14360 115 0 0
sp_client_order 21720 21720 0 0.00 4 48495 351 0 0
sp_customer_reserve 481392 481392 0 0.00 0 72992 182 0 0
sp_fill_shopping_cart 33167 33167 0 0.00 1 13706 37 0 0
sp_fill_shopping_cart_clo_res 481376 481376 0 0.00 0 14308 14 0 0
sp_get_clo_for_invoice 5202 5202 0 0.00 0 22291 293 0 0
sp_kill_cost_storno 15677 15677 0 0.00 0 11858 61 0 0
sp_kill_qstorno_ret_qs2qd 1085536 1085536 0 0.00 0 24193 33 0 0
sp_kill_qty_storno 573685 573685 0 0.00 0 36679 70 0 0
sp_lock_selected_doc 21790 21790 0 0.00 0 10118 6 0 0
sp_make_cost_storno 29402 29402 0 0.00 0 20051 52 0 0
sp_multiply_rows_for_pdistr 29402 29402 0 0.00 0 10057 28 0 0
sp_multiply_rows_for_qdistr 21720 21720 0 0.00 0 34712 160 0 0
sp_pay_from_customer 7395 7395 0 0.00 4 10640 101 0 0
sp_pay_to_supplier 6137 6137 0 0.00 4 12428 125 0 0
sp_payment_common 13532 13532 0 0.00 1 8860 24 0 0
sp_qd_handle_on_cancel_clo 1820 1820 0 0.00 0 6445 302 0 0
sp_qd_handle_on_invoice_upd_sts 10599 10599 0 0.00 0 36676 329 0 0
sp_qd_handle_on_reserve_upd_sts 10668 10668 0 0.00 0 9744 50 0 0
sp_reserve_write_off 10668 10668 0 0.00 6 20317 167 0 0
sp_supplier_invoice 5724 5724 0 0.00 7 91186 3332 0 0
sp_supplier_order 5725 5725 0 0.00 5 28836 1306 0 0
srv_aggregate_perf_data 85 85 0 0.00 3231 606088 24669 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1549 140751 44019 0 0
srv_find_qd_qs_mism 1053924 1053924 0 0.00 0 20041 6 0 0
srv_make_invnt_saldo 606 606 0 0.00 34 53410 1011 0 0
srv_make_money_saldo 769 769 0 0.00 1 4745 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 91361 247271 193948 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5226 5226 0 0.00 0 13190 173 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 7688 125 0 0
t$perf-norm:sp_cancel_client_order 1820 1820 0 0.00 0 3381 10 0 0
t$perf-norm:sp_cancel_customer_reserve 740 740 0 0.00 0 1040 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 3660 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 281 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1773 19 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 25871 1505 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 2230 10 0 0
t$perf-norm:sp_client_order 21720 21720 0 0.00 0 7560 17 0 0
t$perf-norm:sp_customer_reserve 1081 1081 0 0.00 0 5993 21 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 8258 12 0 0
t$perf-norm:sp_pay_to_supplier 6137 6137 0 0.00 0 5975 12 0 0
t$perf-norm:sp_reserve_write_off 10668 10668 0 0.00 0 7117 15 0 0
t$perf-norm:sp_supplier_invoice 5724 5724 0 0.00 0 9499 23 0 0
t$perf-norm:sp_supplier_order 5725 5725 0 0.00 0 8661 21 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 88 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 3567 63 0 0
t$perf-norm:srv_make_invnt_saldo 606 606 0 0.00 0 1526 24 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 1163 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 29 9 0 0
v_add_invoice_to_stock 10704 10404 300 2.80 0 4836 18 0 300
v_all_customers 39420 39420 0 0.00 0 103 1 0 0
v_all_suppliers 31584 31584 0 0.00 0 18 1 0 0
v_all_wares 43440 43440 0 0.00 0 117 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1198 8 0 0
v_cancel_client_order 3640 3640 0 0.00 0 1156 3 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 641 4 0 0
v_cancel_customer_reserve 1480 1480 0 0.00 0 253 3 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1817 9 0 0
v_cancel_supplier_invoice 862 836 26 3.01 0 1032 14 0 26
v_cancel_supplier_order 810 810 0 0.00 0 856 5 0 0
v_cancel_write_off 2082 2082 0 0.00 0 904 13 0 0
v_max_id_clo_ord 5725 5725 0 0.00 0 621 1 0 0
v_max_id_clo_res 1081 1081 0 0.00 0 2362 16 0 0
v_max_id_ord_sup 5722 5722 0 0.00 0 4061 5 0 0
v_max_non_paid_invoice 1792 1792 0 0.00 0 2414 5 0 0
v_max_non_paid_realizn 3925 3925 0 0.00 0 774 3 0 0
v_min_id_clo_ord 5725 5725 0 0.00 0 4992 7 0 0
v_min_id_clo_res 1081 1081 0 0.00 0 3759 13 0 0
v_min_id_ord_sup 5722 5722 0 0.00 0 7113 7 0 0
v_min_non_paid_invoice 6137 6137 0 0.00 0 6047 16 0 0
v_min_non_paid_realizn 7395 7395 0 0.00 0 5482 13 0 0
v_our_firm 10960 10960 0 0.00 0 19 1 0 0
v_reserve_write_off 21336 21336 0 0.00 0 3639 4 0 0
x_lock_depdocs_on_canc_invoice 11043 11043 0 0.00 0 19049 135 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 6755 372 0 0
x_make_qstorno_1000_1200 5725 5725 0 0.00 1 28822 1225 0 0
x_make_qstorno_1200_2000 5724 5724 0 0.00 3 82092 3198 0 0
x_make_qstorno_2100_3300 481391 481391 0 0.00 0 72991 165 0 0

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

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:07:53 2 8192000000 8801291696 8812597248 7256432 0.088 2 1 2 0 7375808 56816 62560
20:08:53 30452 8192000000 10401640304 10725249024 1445109792 14.995 100 86 172 0 1480441392 6819296 28512304
20:10:28 21748 8192000000 10465077024 10800304128 1513700960 15.595 100 86 172 0 1551015536 9753520 27561056
20:11:59 9126 8192000000 10507819760 10852335616 1558587056 15.984 100 86 171 0 1596100992 10717920 26796016
20:13:18 12033 8192000000 10592454032 10952916992 1642524864 16.701 100 78 156 0 1679146928 12999712 23622352
20:14:37 8320 8192000000 10648197152 11020914688 1712339344 17.288 100 78 157 1 1747360096 12022496 22998256
20:15:47 4045 8192000000 10708925600 11088863232 1773215712 17.794 100 78 154 0 1806742336 11843760 21682864
20:16:54 2781 8192000000 10756676592 11139731456 1817132560 18.154 100 89 178 1 1857001104 14280208 25588336
20:17:58 1698 8192000000 10805968080 11196174336 1868496528 18.572 100 84 164 1 1903267568 11495296 23275744
20:19:02 31206 8192000000 10815118608 11221905408 1882726992 18.687 100 56 111 1 1909238944 10655200 15856752
20:20:35 4827 8192000000 10810675664 11231490048 1889934080 18.745 100 34 68 3 1907398672 7887216 9577376
20:21:40 4952 8192000000 10803531008 11235373056 1895260720 18.788 100 28 56 1 1909872240 6886224 7725296
20:22:47 34896 8192000000 10823886016 11252981760 1903051440 18.851 100 37 72 1 1919001408 5595264 10354704
20:24:22 2480 8192000000 10838595424 11265085440 1918969712 18.979 100 33 66 1 1934219344 6314864 8934768
20:25:29 6525 8192000000 10833561312 11269406720 1920906176 18.994 100 33 66 3 1936508592 6997456 8604960
20:26:42 61307 8192000000 10786496288 11219378176 1848765408 18.412 97 29 58 0 1862588576 5441184 8381984
20:28:51 5710 8192000000 10769931184 11197759488 1850221488 18.424 99 23 46 1 1860662000 4359040 6081472
20:29:57 5275 8192000000 10735237696 11155599360 1806057504 18.064 99 17 34 0 1816902976 6170128 4675344
20:31:05 14954 8192000000 10707628832 11108544512 1724620768 17.391 96 33 66 1 1738955920 5304560 9030592
20:32:27 21016 8192000000 10708750096 11117027328 1735763248 17.483 96 28 58 0 1753059472 9648640 7647584
20:33:50 5579 8192000000 10745453040 11169480704 1836535936 18.313 100 36 73 2 1853791040 5956208 11298896
20:34:56 6460 8192000000 10770119216 11191447552 1853041488 18.447 100 33 66 0 1868955536 6358176 9555872
20:36:03 63892 8192000000 10803212016 11211534336 1866371616 18.555 100 24 47 0 1878487024 5134832 6980576
20:38:15 19959 8192000000 10833283056 11256467456 1909399232 18.902 100 42 84 1 1929414272 7765584 12249456
20:39:40 13380 8192000000 10879009424 11297198080 1941841456 19.161 100 53 106 2 1967812240 10399584 15571200
20:41:03 14834 8192000000 10879706256 11309166592 1951019024 19.235 100 52 104 2 1978994480 11702704 16272752
20:42:24 6563 8192000000 10901735056 11324317696 1965406000 19.349 100 52 100 4 1990546720 10714976 14425744
20:43:35 10669 8192000000 10895279968 11339407360 1968047616 19.370 100 35 71 1 1994764288 16016720 10699952
20:44:51 10441 8192000000 10926529360 11353427968 1978803984 19.455 100 53 105 1 2010791616 16748880 15238752
20:46:05 2923 8192000000 10930440224 11357679616 1982411248 19.484 100 62 124 2 2018321520 17349888 18560384
20:47:12 29122 8192000000 10895164064 11331321856 1954632304 19.263 99 48 96 0 1987136752 18247232 14257216
20:48:50 3080 8192000000 10944917888 11374755840 1990557248 19.548 100 64 129 0 2032262656 22694384 19011024
20:49:58 2077 8192000000 10967784960 11385720832 2007585856 19.683 100 86 170 0 2051602992 19977296 24039840
20:51:02 2037 8192000000 10933574288 11351683072 1969911808 19.385 100 73 146 0 2016103968 23134048 23058112
20:52:09 3904 8192000000 10933826864 11356782592 1973638624 19.414 100 65 130 1 2019935328 25403904 20892800
20:53:18 10816 8192000000 10947794096 11376709632 1991890848 19.559 100 56 110 2 2029612544 21484784 16236912
20:54:33 7970 8192000000 10922657680 11342872576 1943739776 19.177 99 65 131 0 1994257392 31395472 19122144
20:55:45 1548 8192000000 10941087248 11348443136 1965074736 19.346 100 88 174 2 2022249632 31139920 26034976
20:56:53 6097 8192000000 10919832560 11336019968 1947129552 19.204 100 62 123 1 2000927600 34308672 19489376
20:58:04 2328 8192000000 10928823152 11337445376 1952766960 19.249 100 81 160 3 2007686176 31289312 23629904
20:59:16 3951 8192000000 10905159312 11325677568 1935445264 19.110 100 71 142 2 1992533888 33899600 23189024
21:00:30 15559 8192000000 10898439328 11338964992 1948615264 19.215 100 35 70 2 1989480416 28944896 11920256
21:01:57 13003 8192000000 10917979856 11353473024 1954338336 19.261 100 44 88 1 2008321968 38156960 15826672
21:03:12 25378 8192000000 10905102352 11352473600 1949704208 19.224 100 27 53 0 1990958736 32375184 8879344
21:04:43 21309 8192000000 10907104464 11362852864 1942703616 19.168 100 19 38 0 1992324288 42808784 6811888
21:06:26 23989 8192000000 10846121264 11305320448 1905188112 18.868 99 13 26 0 1920793504 12076032 3529360
21:08:10 63857 8192000000 10905382304 11351941120 1962500624 19.326 100 24 48 2 1985789888 16667632 6621632
21:10:15 127474 8192000000 10888740848 11344760832 1844871328 18.380 94 8 16 0 1863081296 15619568 2590400
21:13:25 98396 8192000000 10890425968 11356348416 1929071520 19.059 98 45 88 1 1961374256 18788816 13513920
21:16:04 71386 8192000000 10903899456 11359404032 1951544336 19.239 99 38 76 2 1978313168 15807072 10961760
21:18:19 42974 8192000000 10900365184 11363979264 1960110352 19.307 100 17 34 1 1981754016 16704208 4939456
21:20:05 59626 8192000000 10918809328 11386892288 1975865856 19.432 100 33 65 2 2004544208 19368160 9310192
21:22:10 39807 8192000000 10900585200 11386281984 1972544096 19.406 100 11 23 1 1993827664 18141488 3142080
21:23:50 24606 8192000000 10885426144 11368525824 1967303504 19.364 100 12 24 0 1981904896 10744048 3857344
21:25:16 41792 8192000000 10879242192 11355815936 1944692976 19.184 99 5 10 0 1956623808 10476112 1454720
21:27:07 61162 8192000000 10900109024 11374436352 1976609984 19.438 100 21 42 1 1994093424 11207280 6276160
21:29:14 50847 8192000000 10894498400 11372740608 1974995728 19.425 100 25 50 0 1991025504 9017584 7012192
21:31:18 76560 8192000000 10891601456 11374395392 1955432992 19.270 99 4 8 0 1964287312 8089488 764832
21:33:35 47539 8192000000 10899585168 11368620032 1956060160 19.275 99 19 38 1 1972420720 10887248 5473312
21:35:38 29154 8192000000 10908610576 11375718400 1963139328 19.331 99 35 70 1 1987586624 14384992 10062304
21:37:11 67585 8192000000 10884351456 11347591168 1828308720 18.246 92 30 60 1 1847837696 11272432 8256544
21:39:28 62431 8192000000 10857238016 11312857088 1752745040 17.624 89 16 32 1 1765412224 7980192 4686992
21:41:32 57259 8192000000 10885792528 11345903616 1912913488 18.930 98 28 54 2 1930679520 10418752 7347280
21:43:29 66979 8192000000 10876641552 11332554752 1888513536 18.734 97 27 54 0 1909057040 13077168 7466336
21:45:40 47760 8192000000 10853609984 11318075392 1886709856 18.719 98 21 42 1 1901725264 9218832 5796576
21:47:33 62385 8192000000 10824925232 11292172288 1898835328 18.817 98 43 86 0 1922482192 11462544 12184320
21:49:40 25374 8192000000 10868354688 11330396160 1932841184 19.090 100 24 48 0 1956368176 16363456 7163536
21:51:07 52813 8192000000 10833568352 11305926656 1887509552 18.726 98 10 20 2 1901283968 9958064 3816352
21:53:23 65342 8192000000 10834483184 11302420480 1882773168 18.687 98 9 18 1 1898574656 13367728 2433760
21:55:32 62929 8192000000 10857074720 11327176704 1907570432 18.887 99 12 24 0 1920931984 10015152 3346400
21:57:35 40712 8192000000 10864656192 11329421312 1906201376 18.876 98 26 52 0 1926414400 11866912 8346112
21:59:23 67010 8192000000 10855921248 11316572160 1876630592 18.638 97 15 30 0 1897167984 15764432 4772960
22:01:32 42805 8192000000 10870241424 11339046912 1928110720 19.052 99 22 44 0 1943108624 9095936 5901968
22:03:16 62214 8192000000 10887362736 11353444352 1912173936 18.924 98 16 32 1 1931453056 14702688 4576432
22:05:25 140751 8192000000 10892940544 11378905088 1902059216 18.843 97 5 10 0 1915733184 12654320 1019648
22:08:54 84671 8192000000 10884597248 11359432704 1945763232 19.193 99 19 38 0 1964413744 13159568 5490944
22:11:20 47908 8192000000 10860227536 11337474048 1917977136 18.971 98 15 31 0 1928695216 6157808 4560272
22:13:09 52760 8192000000 10873450928 11344334848 1941070480 19.155 99 16 33 0 1953122224 7770496 4281248
22:15:02 52637 8192000000 10881389568 11366350848 1964359248 19.341 100 9 18 0 1983430592 16504064 2567280
22:16:55 88505 8192000000 10900467808 11365920768 1906127392 18.876 97 16 32 0 1929094096 17271616 5695088
22:19:39 57391 8192000000 10916720336 11382046720 1948524096 19.215 99 26 52 0 1978207856 22273584 7410176
22:21:44 54594 8192000000 10869096128 11339018240 1868708848 18.574 95 14 28 1 1891651584 18854832 4087904
22:23:51 50766 8192000000 10872300880 11328507904 1885534240 18.710 97 16 32 0 1896976912 6689936 4752736
22:25:51 45928 8192000000 10864979360 11331219456 1902426496 18.846 98 27 54 0 1923662768 13764784 7471488
22:27:38 117535 8192000000 10861375456 11328892928 1812553088 18.117 93 25 50 0 1831983792 12291808 7138896
22:30:50 92549 8192000000 10827181072 11286429696 1816063056 18.145 94 24 48 0 1831822080 9239136 6519888
22:33:24 33493 8192000000 10823323536 11304050688 1891366288 18.757 99 5 10 1 1904102368 11615744 1120336
22:34:59 66681 8192000000 10833248960 11311861760 1901045040 18.835 99 9 18 0 1916377568 12482224 2850304
22:37:06 49300 8192000000 9454322160 9769201664 604599152 6.873 32 2 4 0 609131888 4010944 521792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

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

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          246482
MON$OLDEST_ACTIVE               246483
MON$OLDEST_SNAPSHOT             246483
MON$NEXT_TRANSACTION            246483
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                       2023040
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.33495 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33495 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33495 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33495 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Sat Sep 11 22:37:57 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
--- end of diff output ---

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

11.09.21 22:39:55. 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-09-11 22:39:57 No severe PSQL-related problems occured
11.09.21 22:39:59. End of report.