Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2022 01: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 16559096 kB
MemAvailable 31298828 kB
Buffers 0 kB
Cached 15088008 kB
SwapCached 1196 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.9
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-01-03 01:38:00
DTS_END                         2022-01-03 01:38:00.8040
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41136
TRN_ID                          243954
ATT_ID                          642
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: 9119.14 20256 1094297
customer order: creation 178.23 363 21388
customer order: refuse 15.14 384 1817
order to supplier: creation 47.62 1412 5714
order to supplier: removal 3.38 97685 405
invoice (draft): creation 47.43 3631 5692
invoice (draft): removal 50.43 812 6052
invoice accept: apply 42.83 16702 5139
invoice accept: cancel 45.50 820 5460
customer reserve: creation 3914.74 167 469769
customer reserve: removal 4474.33 58 536919
realization accept: apply 85.60 186 10272
realization accept: cancel 81.22 110 9747
payment from customer: creation 59.92 141 7190
payment from customer: removal 6.04 85 725
payment to supplier: creation 50.83 150 6100
payment to supplier: removal 3.47 81 416
service: total inventory turnovers 4.38 1348 525
service: total monetary turnovers 7.18 23 861
service: aggregate perf. data 0.85 17681 102
service: refresh index statistics 0.03 263272 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2022 01:38:48 to 03-01-2022 01:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6456
TEST_TIME 2 7398
TEST_TIME 3 6066
TEST_TIME 4 5496
TEST_TIME 5 4822
TEST_TIME 6 5032
TEST_TIME 7 5676
TEST_TIME 8 5885
TEST_TIME 9 6326
TEST_TIME 10 6595
TEST_TIME 11 6638
TEST_TIME 12 6543
TEST_TIME 13 6858
TEST_TIME 14 6962
TEST_TIME 15 7068
TEST_TIME 16 7283
TEST_TIME 17 7424
TEST_TIME 18 7701
TEST_TIME 19 8232
TEST_TIME 20 8169
TEST_TIME 21 8202
TEST_TIME 22 8825
TEST_TIME 23 8776
TEST_TIME 24 9010
TEST_TIME 25 9123
TEST_TIME 26 9439
TEST_TIME 27 9598
TEST_TIME 28 9610
TEST_TIME 29 10186
TEST_TIME 30 11059
TEST_TIME 31 11171
TEST_TIME 32 10852
TEST_TIME 33 10669
TEST_TIME 34 10531
TEST_TIME 35 10563
TEST_TIME 36 10410
TEST_TIME 37 10271
TEST_TIME 38 10084
TEST_TIME 39 10183
TEST_TIME 40 10096
TEST_TIME 41 9963
TEST_TIME 42 9952
TEST_TIME 43 9853
TEST_TIME 44 9742
TEST_TIME 45 9538
TEST_TIME 46 9553
TEST_TIME 47 9542
TEST_TIME 48 9476
TEST_TIME 49 9348
TEST_TIME 50 9413
TEST_TIME 51 9398
TEST_TIME 52 9409
TEST_TIME 53 9366
TEST_TIME 54 9340
TEST_TIME 55 9225
TEST_TIME 56 9135
TEST_TIME 57 9046
TEST_TIME 58 9076
TEST_TIME 59 9068
TEST_TIME 60 9107
TEST_TIME 61 9103
TEST_TIME 62 9118
TEST_TIME 63 9193
TEST_TIME 64 9171
TEST_TIME 65 9214
TEST_TIME 66 9284
TEST_TIME 67 9270
TEST_TIME 68 9245
TEST_TIME 69 9301
TEST_TIME 70 9291
TEST_TIME 71 9309
TEST_TIME 72 9462
TEST_TIME 73 9428
TEST_TIME 74 9461
TEST_TIME 75 9451
TEST_TIME 76 9379
TEST_TIME 77 9336
TEST_TIME 78 9301
TEST_TIME 79 9263
TEST_TIME 80 9265
TEST_TIME 81 9230
TEST_TIME 82 9208
TEST_TIME 83 9272
TEST_TIME 84 9222
TEST_TIME 85 9218
TEST_TIME 86 9151
TEST_TIME 87 9183
TEST_TIME 88 9155
TEST_TIME 89 9153
TEST_TIME 90 9234
TEST_TIME 91 9246
TEST_TIME 92 9238
TEST_TIME 93 9329
TEST_TIME 94 9369
TEST_TIME 95 9381
TEST_TIME 96 9415
TEST_TIME 97 9438
TEST_TIME 98 9371
TEST_TIME 99 9497
TEST_TIME 100 9455
TEST_TIME 101 9385
TEST_TIME 102 9382
TEST_TIME 103 9322
TEST_TIME 104 9283
TEST_TIME 105 9284
TEST_TIME 106 9264
TEST_TIME 107 9254
TEST_TIME 108 9225
TEST_TIME 109 9184
TEST_TIME 110 9186
TEST_TIME 111 9183
TEST_TIME 112 9198
TEST_TIME 113 9174
TEST_TIME 114 9163
TEST_TIME 115 9182
TEST_TIME 116 9192
TEST_TIME 117 9198
TEST_TIME 118 9236
TEST_TIME 119 9200
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-01-2022 01:38:52 to 03-01-2022 01:38:55.

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 1530200 1530200 0 0.00 0 20178 6 0 0
doc_list_biud 1530200 1530200 0 0.00 0 38024 26 0 0
sp_add_invoice_to_stock 5277 5139 138 2.61 24 159538 16702 0 138
sp_cancel_adding_invoice 5460 5460 0 0.00 5 81289 821 0 0
sp_cancel_client_order 1817 1817 0 0.00 4 13615 385 0 0
sp_cancel_customer_reserve 536919 536919 0 0.00 1 38034 58 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 2 9332 85 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 6619 81 0 0
sp_cancel_supplier_invoice 6063 6052 11 0.18 2 20710 812 0 11
sp_cancel_supplier_order 405 405 0 0.00 7 940944 97686 0 0
sp_cancel_write_off 9747 9747 0 0.00 1 10373 111 0 0
sp_client_order 21388 21388 0 0.00 3 54310 363 0 0
sp_customer_reserve 469769 469769 0 0.00 0 82712 168 0 0
sp_fill_shopping_cart 32788 32788 0 0.00 1 27940 42 0 0
sp_fill_shopping_cart_clo_res 469761 469761 0 0.00 0 14338 13 0 0
sp_get_clo_for_invoice 5127 5127 0 0.00 0 36066 301 0 0
sp_kill_cost_storno 16348 16348 0 0.00 0 12389 58 0 0
sp_kill_qstorno_ret_qs2qd 1090042 1090042 0 0.00 0 34933 32 0 0
sp_kill_qty_storno 575798 575798 0 0.00 0 38023 69 0 0
sp_lock_selected_doc 21242 21242 0 0.00 0 7092 5 0 0
sp_make_cost_storno 28688 28688 0 0.00 0 20140 58 0 0
sp_multiply_rows_for_pdistr 28688 28688 0 0.00 0 13830 28 0 0
sp_multiply_rows_for_qdistr 21388 21388 0 0.00 0 54302 151 0 0
sp_pay_from_customer 7190 7190 0 0.00 4 30513 141 0 0
sp_pay_to_supplier 6100 6100 0 0.00 4 20188 151 0 0
sp_payment_common 13290 13290 0 0.00 1 30488 39 0 0
sp_qd_handle_on_cancel_clo 1817 1817 0 0.00 0 13580 338 0 0
sp_qd_handle_on_invoice_upd_sts 10586 10586 0 0.00 0 18477 326 0 0
sp_qd_handle_on_reserve_upd_sts 10272 10272 0 0.00 0 15611 63 0 0
sp_reserve_write_off 10272 10272 0 0.00 6 17230 186 0 0
sp_supplier_invoice 5692 5692 0 0.00 6 75668 3632 0 0
sp_supplier_order 5714 5714 0 0.00 5 57568 1413 0 0
srv_aggregate_perf_data 102 102 0 0.00 2684 217900 17681 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1072 124008 46999 0 0
srv_find_qd_qs_mism 1045789 1045789 0 0.00 0 16096 5 0 0
srv_make_invnt_saldo 525 525 0 0.00 41 67476 1349 0 0
srv_make_money_saldo 861 861 0 0.00 0 972 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 175695 361992 263272 0 0
t$perf-abend:sp_add_invoice_to_stock 138 138 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5139 5139 0 0.00 0 26003 233 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 0 7729 109 0 0
t$perf-norm:sp_cancel_client_order 1817 1817 0 0.00 0 1500 10 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1030 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 2868 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 351 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 15172 47 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 20006 1453 0 0
t$perf-norm:sp_cancel_write_off 969 969 0 0.00 0 686 4 0 0
t$perf-norm:sp_client_order 21388 21388 0 0.00 0 23150 23 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 3340 11 0 0
t$perf-norm:sp_pay_from_customer 7190 7190 0 0.00 0 11179 15 0 0
t$perf-norm:sp_pay_to_supplier 6100 6100 0 0.00 0 26213 15 0 0
t$perf-norm:sp_reserve_write_off 10272 10272 0 0.00 0 27798 16 0 0
t$perf-norm:sp_supplier_invoice 5692 5692 0 0.00 0 24716 28 0 0
t$perf-norm:sp_supplier_order 5714 5714 0 0.00 0 9710 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 664 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 525 525 0 0.00 0 2700 30 0 0
t$perf-norm:srv_make_money_saldo 861 861 0 0.00 0 1902 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10528 10252 276 2.62 0 9051 24 0 276
v_all_customers 38732 38732 0 0.00 0 19 0 0 0
v_all_suppliers 31758 31758 0 0.00 0 16 1 0 0
v_all_wares 42776 42776 0 0.00 0 118 1 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 538 5 0 0
v_cancel_client_order 3634 3634 0 0.00 0 9251 10 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 1711 8 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 2818 9 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 2059 10 0 0
v_cancel_supplier_invoice 860 838 22 2.55 0 1095 16 0 22
v_cancel_supplier_order 810 810 0 0.00 0 2627 10 0 0
v_cancel_write_off 1938 1938 0 0.00 0 1323 15 0 0
v_max_id_clo_ord 5714 5714 0 0.00 0 27910 7 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 2908 24 0 0
v_max_id_ord_sup 5685 5685 0 0.00 0 6376 6 0 0
v_max_non_paid_invoice 1620 1620 0 0.00 0 6640 8 0 0
v_max_non_paid_realizn 3856 3856 0 0.00 0 9150 5 0 0
v_min_id_clo_ord 5714 5714 0 0.00 0 2411 7 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 8726 14 0 0
v_min_id_ord_sup 5685 5685 0 0.00 0 6759 6 0 0
v_min_non_paid_invoice 6100 6100 0 0.00 0 17507 27 0 0
v_min_non_paid_realizn 7190 7190 0 0.00 0 30482 34 0 0
v_our_firm 10712 10712 0 0.00 0 15 1 0 0
v_reserve_write_off 20544 20544 0 0.00 0 14486 10 0 0
x_lock_depdocs_on_canc_invoice 11093 11093 0 0.00 0 16505 112 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 11620 349 0 0
x_make_qstorno_1000_1200 5714 5714 0 0.00 2 57559 1325 0 0
x_make_qstorno_1200_2000 5692 5692 0 0.00 2 75660 3482 0 0
x_make_qstorno_2100_3300 469769 469769 0 0.00 0 82709 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2022 01:38:56 to 03-01-2022 01:39:00.

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
23:08:04 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:05 39029 8192000000 9905720336 10217775104 982829760 10.712 100 88 176 1 1012628240 6302368 23496112
23:10:51 12201 8192000000 9939952432 10261749760 1024223200 11.113 100 92 184 0 1056394944 7620928 24550816
23:12:06 12372 8192000000 9977625152 10311278592 1064290720 11.498 100 81 162 0 1092968640 8182704 20495216
23:13:26 10348 8192000000 10032242880 10380955648 1117413632 12.003 100 79 159 1 1146592912 9733056 19446224
23:14:38 9851 8192000000 10085821472 10447179776 1175403248 12.547 100 74 146 3 1204276384 12250416 16622720
23:15:50 6940 8192000000 10140603472 10508103680 1221790800 12.978 100 92 183 2 1255349040 13055232 20503008
23:17:00 4239 8192000000 10171262528 10545446912 1250679696 13.244 100 86 172 1 1282860240 13710176 18470368
23:18:05 2060 8192000000 10199615904 10581385216 1284089984 13.550 100 85 171 2 1315609664 13234848 18284832
23:19:10 1194 8192000000 10217689056 10603257856 1302427136 13.717 100 95 192 1 1336766512 12604544 21734832
23:20:13 1973 8192000000 10212273120 10609491968 1307792320 13.766 100 66 135 0 1332816768 9577568 15446880
23:21:17 54643 8192000000 10226238512 10624983040 1316327664 13.843 100 39 78 0 1330355600 4925520 9102416
23:23:12 5482 8192000000 10218788384 10635849728 1321249888 13.888 100 31 61 1 1339177360 11175920 6751552
23:24:20 10445 8192000000 10219891184 10638745600 1328902864 13.957 100 13 26 0 1337855408 5769664 3182880
23:25:33 10878 8192000000 10219071056 10644754432 1334319648 14.006 100 13 27 0 1342928608 5959968 2648992
23:26:47 34601 8192000000 10219334496 10643283968 1321578240 13.891 99 25 49 2 1333007904 6075680 5353984
23:28:23 5190 8192000000 10207596128 10622595072 1305219536 13.743 99 30 61 0 1318257936 6319520 6718880
23:29:30 10929 8192000000 10175051328 10585047040 1247236544 13.213 96 16 32 0 1256596128 5800672 3558912
23:30:44 69143 8192000000 10112527328 10515005440 1108148096 11.915 88 36 72 1 1121823616 4787072 8888448
23:32:54 8114 8192000000 10137335184 10535317504 1235207152 13.102 99 23 46 0 1246096432 5342416 5546864
23:34:05 7778 8192000000 10174602704 10582491136 1282416768 13.535 100 19 38 0 1291639776 4958800 4264208
23:35:16 11385 8192000000 10190193520 10604933120 1299298000 13.689 100 19 38 3 1309420816 5836704 4286112
23:36:28 30682 8192000000 10186565120 10605944832 1303760656 13.729 100 22 42 1 1314277888 5799008 4718224
23:38:00 4515 8192000000 10217012336 10623381504 1317109984 13.851 100 32 62 2 1329010048 5350928 6549136
23:39:08 37744 8192000000 10258186208 10668806144 1351130832 14.158 100 45 90 1 1369488992 8210128 10148032
23:41:11 6645 8192000000 10278091744 10696986624 1369292512 14.321 100 43 87 6 1388080128 8075136 10712480
23:42:23 78709 8192000000 10289600016 10715811840 1383130128 14.445 100 44 88 2 1407938816 14415360 10393328
23:44:55 20084 8192000000 10311888576 10737897472 1391448272 14.519 100 43 86 1 1423342832 21977680 9916880
23:46:30 12166 8192000000 10317055328 10737582080 1389071296 14.498 99 42 84 4 1418430800 18608832 10750672
23:47:47 5436 8192000000 10331626256 10754330624 1404119984 14.632 100 56 111 2 1436659488 19568064 12971440
23:49:10 10793 8192000000 10341976560 10769043456 1414918912 14.728 100 50 100 0 1452102432 25331728 11851792
23:50:25 4805 8192000000 10342668464 10766282752 1391324256 14.518 98 58 116 1 1434884080 29387376 14172448
23:51:36 3011 8192000000 10353845920 10769768448 1402718560 14.619 99 75 149 0 1453732784 32826320 18187904
23:52:45 5236 8192000000 10345761504 10770530304 1401497520 14.608 99 66 132 4 1450549376 32114144 16937712
23:54:03 8801 8192000000 10345665296 10764013568 1400793776 14.602 100 72 142 3 1452357296 33475184 18088336
23:55:17 12369 8192000000 10323638080 10750906368 1367676560 14.306 98 34 68 2 1411541568 34285744 9579264
23:56:34 18670 8192000000 10326494768 10749898752 1362440048 14.259 98 33 65 4 1414138288 43524912 8173328
23:57:54 18984 8192000000 10314862912 10736664576 1352217568 14.167 98 36 71 1 1402533488 41152800 9163120
23:59:24 1072 8192000000 10346705712 10751733760 1368140288 14.310 100 89 178 0 1444406688 53269680 22996720
00:00:30 12710 8192000000 10342896048 10771562496 1392124464 14.525 100 42 84 2 1451363424 47764976 11473984
00:02:02 7573 8192000000 10363085728 10791784448 1409343200 14.678 100 48 96 2 1472912576 50772432 12796944
00:03:13 17590 8192000000 10367118064 10799538176 1389196528 14.499 99 33 66 1 1454501344 56416000 8888816
00:04:45 31733 8192000000 10343994608 10800046080 1386842528 14.478 99 25 50 1 1448276528 54342048 7091952
00:06:17 19651 8192000000 10333319920 10775760896 1370001632 14.327 100 22 45 1 1435383264 59107312 6274320
00:07:46 36093 8192000000 10300831776 10773553152 1370707376 14.333 99 21 42 0 1432942112 56294336 5940400
00:09:23 43340 8192000000 10285911872 10719109120 1352182640 14.167 99 9 16 1 1361657648 7585328 1889680
00:11:07 124008 8192000000 10299815712 10740109312 1358186592 14.221 98 31 62 1 1384977904 19757328 7033984
00:14:14 101487 8192000000 10302814016 10760916992 1379563168 14.413 99 23 46 2 1402295616 17264848 5467600
00:17:00 98680 8192000000 10309053632 10765111296 1396749536 14.566 100 17 34 1 1414407488 13542048 4115904
00:19:53 83848 8192000000 10315237728 10767814656 1402534112 14.618 100 16 32 1 1418523216 12782544 3206560
00:22:21 112118 8192000000 10314012912 10772762624 1406793776 14.655 100 31 62 1 1426410192 12292576 7323840
00:25:14 44769 8192000000 10310632352 10771247104 1408726144 14.673 100 31 62 2 1424535280 9128496 6680640
00:27:00 41143 8192000000 10306502016 10762043392 1396095776 14.560 99 33 66 1 1412359712 8679152 7584784
00:28:45 36539 8192000000 10309101616 10769068032 1409240000 14.677 100 28 56 1 1425095392 9038352 6817040
00:30:22 48246 8192000000 10280380896 10744496128 1377308432 14.392 99 21 42 1 1392126048 10235056 4582560
00:32:11 39516 8192000000 10277495536 10740203520 1378333456 14.402 99 12 24 1 1388326992 7618256 2375280
00:34:05 123263 8192000000 10282346176 10751709184 1348121856 14.131 96 21 42 0 1366103712 13137008 4844848
00:37:33 48097 8192000000 10293555184 10760704000 1388250112 14.490 100 26 52 2 1410280160 16322448 5707600
00:39:21 43437 8192000000 10278701008 10732949504 1319165568 13.869 95 13 26 0 1327596384 5911504 2519312
00:41:08 50377 8192000000 10255684640 10712059904 1302631424 13.719 94 15 30 2 1317081776 11164528 3285824
00:42:58 72797 8192000000 10267854128 10693787648 1281676800 13.528 94 25 50 0 1297802224 10423728 5701696
00:45:12 33072 8192000000 10271040208 10712002560 1318573984 13.864 97 23 46 0 1337091088 13425664 5091440
00:46:53 48190 8192000000 10277533760 10706866176 1331656384 13.982 98 38 76 2 1351008208 11035472 8316352
00:48:42 78615 8192000000 10259373424 10704945152 1316706448 13.847 97 31 60 1 1339971296 16300112 6964736
00:51:01 72203 8192000000 10258038976 10695643136 1311586256 13.800 97 21 42 1 1328787120 12287120 4913744
00:53:20 122302 8192000000 10248225456 10687201280 1324103280 13.914 98 11 22 0 1339854768 12884336 2867152
00:56:25 51775 8192000000 10273649472 10706120704 1348823424 14.137 99 46 90 2 1371137264 12185328 10128512
00:58:26 70222 8192000000 10278177440 10732961792 1364866320 14.281 99 10 20 0 1382330720 14728752 2735648
01:00:36 42057 8192000000 10283313296 10729439232 1373319888 14.357 100 15 30 0 1391626864 14558672 3748304
01:02:21 32165 8192000000 10275431168 10718650368 1366832192 14.299 100 20 39 2 1377906944 5894960 5179792
01:03:55 48233 8192000000 10259897232 10717384704 1356151456 14.203 99 5 10 1 1364219760 6824800 1243504
01:05:48 33836 8192000000 10291061456 10738561024 1379311872 14.410 100 27 53 2 1395626704 10035616 6279216
01:07:23 99864 8192000000 10295964736 10749562880 1370175424 14.329 99 5 10 0 1385549056 14592672 780960
01:10:03 66681 8192000000 10302940096 10755633152 1368678976 14.315 98 16 31 0 1383101632 11130880 3291776
01:12:15 58617 8192000000 10297315888 10760282112 1376614528 14.386 99 17 34 2 1393145072 12805008 3725536
01:14:22 32928 8192000000 10295243088 10756239360 1362307376 14.258 98 20 40 1 1385733184 18829648 4596160
01:16:38 17852 8192000000 10313382192 10747502592 1373858816 14.362 99 58 116 1 1400834368 12146480 14829072
01:18:00 100317 8192000000 10280910224 10737782784 1362743136 14.262 98 11 22 1 1379114224 13885360 2485728
01:20:55 81188 8192000000 10296504416 10751733760 1382273664 14.437 100 12 24 0 1395166272 10146800 2745808
01:23:17 56745 8192000000 10291078640 10752720896 1369401504 14.322 98 18 36 1 1384783616 11370496 4011616
01:25:22 61849 8192000000 10278586528 10741084160 1337983504 14.039 96 12 24 1 1354222928 12909824 3329600
01:27:26 89851 8192000000 10245727568 10697105408 1286704816 13.574 95 16 30 1 1301023088 10895504 3422768
01:30:02 72622 8192000000 10250404608 10703224832 1333594624 14.000 98 15 30 0 1347603792 10423504 3585664
01:32:17 25574 8192000000 10271230784 10714857472 1355849008 14.200 99 34 68 2 1373734096 10163792 7721296
01:33:50 63998 8192000000 10253704656 10703056896 1277634592 13.491 93 14 28 0 1292078816 11271888 3172336
01:35:54 45454 8192000000 10253280256 10700795904 1318729360 13.865 97 23 46 0 1333100736 9348320 5023056
01:37:44 15641 8192000000 10264330688 10689744896 1329917408 13.966 99 44 88 1 1349841168 10574832 9348928

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-01-2022 01:39:02 to 03-01-2022 01:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 138 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2022 01:39:18 to 03-01-2022 01:39:20.

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          242030
MON$OLDEST_ACTIVE               244270
MON$OLDEST_SNAPSHOT             244270
MON$NEXT_TRANSACTION            244270
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                       2024192
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:345: 
:346: mscs-test-161.budzdorov.ru    Mon Jan  3 01:38:04 2022
:347:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:348: 
:349: 
:350: mscs-test-161.budzdorov.ru    Mon Jan  3 01:38:07 2022
:351:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:352: 
--- end of diff output ---

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

03.01.22 01:39:21. 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-01-03 01:39:23 No severe PSQL-related problems occured
03.01.22 01:39:24. End of report.