Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2021 01: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 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 16843204 kB
MemAvailable 31488048 kB
Buffers 0 kB
Cached 14864516 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.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 2021-12-11 01:37:58
DTS_END                         2021-12-11 01:37:58.4510
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/48586
TRN_ID                          235599
ATT_ID                          707
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: 9191.10 21808 1102932
customer order: creation 172.99 386 20759
customer order: refuse 14.24 358 1709
order to supplier: creation 45.16 1332 5419
order to supplier: removal 3.42 111827 410
invoice (draft): creation 45.47 3354 5456
invoice (draft): removal 53.08 833 6369
invoice accept: apply 40.60 19222 4872
invoice accept: cancel 47.94 854 5753
customer reserve: creation 3732.69 192 447923
customer reserve: removal 4742.33 63 569080
realization accept: apply 82.80 206 9936
realization accept: cancel 81.49 120 9779
payment from customer: creation 59.19 126 7103
payment from customer: removal 5.83 108 699
payment to supplier: creation 48.11 139 5773
payment to supplier: removal 3.27 77 392
service: total inventory turnovers 5.02 1164 602
service: total monetary turnovers 6.60 33 792
service: aggregate perf. data 0.85 21392 102
service: refresh index statistics 0.03 274373 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-12-2021 01:40:05 to 11-12-2021 01:40:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3695
TEST_TIME 2 3468
TEST_TIME 3 3637
TEST_TIME 4 4676
TEST_TIME 5 4296
TEST_TIME 6 4058
TEST_TIME 7 4385
TEST_TIME 8 4384
TEST_TIME 9 4411
TEST_TIME 10 4756
TEST_TIME 11 5195
TEST_TIME 12 5315
TEST_TIME 13 5731
TEST_TIME 14 6050
TEST_TIME 15 6354
TEST_TIME 16 6408
TEST_TIME 17 6372
TEST_TIME 18 6919
TEST_TIME 19 7435
TEST_TIME 20 7853
TEST_TIME 21 7752
TEST_TIME 22 7969
TEST_TIME 23 8552
TEST_TIME 24 8928
TEST_TIME 25 9620
TEST_TIME 26 10587
TEST_TIME 27 10615
TEST_TIME 28 10513
TEST_TIME 29 10448
TEST_TIME 30 10360
TEST_TIME 31 10142
TEST_TIME 32 9892
TEST_TIME 33 10223
TEST_TIME 34 10103
TEST_TIME 35 10024
TEST_TIME 36 9837
TEST_TIME 37 9749
TEST_TIME 38 9807
TEST_TIME 39 9670
TEST_TIME 40 9783
TEST_TIME 41 9788
TEST_TIME 42 9792
TEST_TIME 43 9669
TEST_TIME 44 9540
TEST_TIME 45 9452
TEST_TIME 46 9440
TEST_TIME 47 9450
TEST_TIME 48 9587
TEST_TIME 49 9521
TEST_TIME 50 9510
TEST_TIME 51 9425
TEST_TIME 52 9819
TEST_TIME 53 9716
TEST_TIME 54 9698
TEST_TIME 55 9738
TEST_TIME 56 9731
TEST_TIME 57 9726
TEST_TIME 58 9746
TEST_TIME 59 9782
TEST_TIME 60 9788
TEST_TIME 61 9662
TEST_TIME 62 9636
TEST_TIME 63 9569
TEST_TIME 64 9503
TEST_TIME 65 9502
TEST_TIME 66 9503
TEST_TIME 67 9507
TEST_TIME 68 9442
TEST_TIME 69 9453
TEST_TIME 70 9418
TEST_TIME 71 9370
TEST_TIME 72 9447
TEST_TIME 73 9390
TEST_TIME 74 9387
TEST_TIME 75 9370
TEST_TIME 76 9405
TEST_TIME 77 9484
TEST_TIME 78 9453
TEST_TIME 79 9505
TEST_TIME 80 9547
TEST_TIME 81 9556
TEST_TIME 82 9569
TEST_TIME 83 9485
TEST_TIME 84 9447
TEST_TIME 85 9454
TEST_TIME 86 9402
TEST_TIME 87 9415
TEST_TIME 88 9348
TEST_TIME 89 9293
TEST_TIME 90 9252
TEST_TIME 91 9267
TEST_TIME 92 9257
TEST_TIME 93 9201
TEST_TIME 94 9233
TEST_TIME 95 9204
TEST_TIME 96 9229
TEST_TIME 97 9267
TEST_TIME 98 9253
TEST_TIME 99 9224
TEST_TIME 100 9221
TEST_TIME 101 9301
TEST_TIME 102 9270
TEST_TIME 103 9287
TEST_TIME 104 9311
TEST_TIME 105 9392
TEST_TIME 106 9371
TEST_TIME 107 9440
TEST_TIME 108 9451
TEST_TIME 109 9383
TEST_TIME 110 9362
TEST_TIME 111 9305
TEST_TIME 112 9283
TEST_TIME 113 9257
TEST_TIME 114 9258
TEST_TIME 115 9253
TEST_TIME 116 9235
TEST_TIME 117 9200
TEST_TIME 118 9198
TEST_TIME 119 9180
TEST_TIME 120 9197

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2021 01:40:09 to 11-12-2021 01:40:12.

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 1518824 1518824 0 0.00 0 15632 7 0 0
doc_list_biud 1518823 1518823 0 0.00 0 31246 30 0 0
sp_add_invoice_to_stock 4955 4872 83 1.67 31 201445 19222 0 83
sp_cancel_adding_invoice 5753 5753 0 0.00 4 69173 854 0 0
sp_cancel_client_order 1709 1709 0 0.00 5 11975 359 0 0
sp_cancel_customer_reserve 569080 569080 0 0.00 1 26755 64 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 2 7607 109 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 7234 77 0 0
sp_cancel_supplier_invoice 6376 6369 7 0.10 1 31255 834 0 7
sp_cancel_supplier_order 410 410 0 0.00 7 1316333 111828 0 0
sp_cancel_write_off 9779 9779 0 0.00 1 15836 120 0 0
sp_client_order 20759 20759 0 0.00 3 27152 387 0 0
sp_customer_reserve 447923 447923 0 0.00 0 198250 192 0 0
sp_fill_shopping_cart 31628 31628 0 0.00 1 7625 35 0 0
sp_fill_shopping_cart_clo_res 447920 447920 0 0.00 0 28760 15 0 0
sp_get_clo_for_invoice 4864 4864 0 0.00 0 31817 282 0 0
sp_kill_cost_storno 16623 16623 0 0.00 0 7122 57 0 0
sp_kill_qstorno_ret_qs2qd 1154718 1154718 0 0.00 0 31242 34 0 0
sp_kill_qty_storno 607899 607899 0 0.00 0 31244 74 0 0
sp_lock_selected_doc 20465 20465 0 0.00 0 9010 8 0 0
sp_make_cost_storno 27676 27676 0 0.00 0 12332 61 0 0
sp_multiply_rows_for_pdistr 27676 27676 0 0.00 0 8734 32 0 0
sp_multiply_rows_for_qdistr 20759 20759 0 0.00 0 27138 177 0 0
sp_pay_from_customer 7103 7103 0 0.00 4 16713 126 0 0
sp_pay_to_supplier 5773 5773 0 0.00 5 8484 140 0 0
sp_payment_common 12876 12876 0 0.00 2 9603 31 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 0 11950 319 0 0
sp_qd_handle_on_invoice_upd_sts 10616 10616 0 0.00 0 15960 345 0 0
sp_qd_handle_on_reserve_upd_sts 9936 9936 0 0.00 0 15240 67 0 0
sp_reserve_write_off 9936 9936 0 0.00 6 15716 206 0 0
sp_supplier_invoice 5456 5456 0 0.00 7 81164 3354 0 0
sp_supplier_order 5419 5419 0 0.00 5 35871 1332 0 0
srv_aggregate_perf_data 102 102 0 0.00 2413 318924 21393 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1869 162166 50426 0 0
srv_find_qd_qs_mism 1056382 1056382 0 0.00 0 15632 6 0 0
srv_make_invnt_saldo 602 602 0 0.00 41 128563 1165 0 0
srv_make_money_saldo 792 792 0 0.00 1 6665 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 137392 436568 274373 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4872 4872 0 0.00 0 27805 205 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 4532 114 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 5698 15 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1803 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 3833 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 13699 43 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 523 10 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 27060 1649 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 19972 35 0 0
t$perf-norm:sp_client_order 20759 20759 0 0.00 0 16234 20 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 7653 16 0 0
t$perf-norm:sp_pay_from_customer 7103 7103 0 0.00 0 11155 11 0 0
t$perf-norm:sp_pay_to_supplier 5773 5773 0 0.00 0 14627 14 0 0
t$perf-norm:sp_reserve_write_off 9936 9936 0 0.00 0 5012 10 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 7662 19 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 4877 19 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 561 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 284 7 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 14362 53 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 3792 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 9892 9726 166 1.67 0 8429 24 0 166
v_all_customers 37438 37438 0 0.00 0 60 1 0 0
v_all_suppliers 30210 30210 0 0.00 0 22 1 0 0
v_all_wares 41518 41518 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2413 13 0 0
v_cancel_client_order 3418 3418 0 0.00 0 4997 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 7548 12 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 4867 10 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3355 9 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 869 16 0 14
v_cancel_supplier_order 820 820 0 0.00 0 552 4 0 0
v_cancel_write_off 1998 1998 0 0.00 0 4768 17 0 0
v_max_id_clo_ord 5419 5419 0 0.00 0 2138 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 2670 16 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 1276 2 0 0
v_max_non_paid_invoice 1537 1537 0 0.00 0 693 4 0 0
v_max_non_paid_realizn 3927 3927 0 0.00 0 3214 5 0 0
v_min_id_clo_ord 5419 5419 0 0.00 0 3218 11 0 0
v_min_id_clo_res 983 983 0 0.00 0 3386 7 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 5004 6 0 0
v_min_non_paid_invoice 5773 5773 0 0.00 0 6336 20 0 0
v_min_non_paid_realizn 7103 7103 0 0.00 0 8880 21 0 0
v_our_firm 10432 10432 0 0.00 0 20 1 0 0
v_reserve_write_off 19872 19872 0 0.00 0 4868 8 0 0
x_lock_depdocs_on_canc_invoice 11728 11728 0 0.00 0 22356 132 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 25461 439 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 1 35860 1248 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 3 81081 3243 0 0
x_make_qstorno_2100_3300 447923 447923 0 0.00 0 198249 174 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-12-2021 01:40:13 to 11-12-2021 01:40:18.

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:02 13 8192000000 8806028480 8819871744 9028768 0.110 4 1 2 0 9131056 59504 42784
23:09:02 33645 8192000000 9916012752 10230296576 994317648 10.823 100 88 176 0 1022707232 5898432 22491152
23:10:42 19686 8192000000 9953406560 10276990976 1031301184 11.181 100 88 176 0 1062402736 8939504 22162048
23:12:10 17428 8192000000 9974139152 10308984832 1063949376 11.494 100 84 168 0 1094888880 10283456 20656048
23:13:32 8754 8192000000 10040191184 10388004864 1125973344 12.083 100 86 172 1 1157317136 11595536 19748256
23:14:55 6128 8192000000 10084828864 10447253504 1173621200 12.531 100 78 156 0 1204472544 12896240 17955104
23:16:04 6198 8192000000 10137220848 10510499840 1220527312 12.967 100 70 140 0 1248813536 11801760 16484464
23:17:12 2749 8192000000 10166966080 10544025600 1252215408 13.259 100 82 164 1 1284400816 14509040 17676368
23:18:17 1709 8192000000 10199912272 10580480000 1279771920 13.511 100 91 180 1 1313846832 13862448 20212464
23:19:21 2398 8192000000 10218023952 10601979904 1294658960 13.647 100 90 179 1 1328278480 13533536 20085984
23:20:26 4318 8192000000 10192052736 10604539904 1305039200 13.741 100 46 92 1 1323812736 8591600 10181936
23:21:31 42675 8192000000 10227831328 10628800512 1313622512 13.819 100 65 126 3 1333595024 5045984 14926528
23:23:15 4529 8192000000 10214206800 10631667712 1321418480 13.890 100 27 52 3 1337694368 10504768 5771120
23:24:24 6027 8192000000 10226592752 10644090880 1333758576 14.001 100 27 54 1 1345469504 5100544 6610384
23:25:33 6416 8192000000 10226322784 10654486528 1341533360 14.071 100 26 52 1 1353572176 6287072 5751744
23:26:43 41092 8192000000 10254246640 10666819584 1343009344 14.085 100 57 115 0 1361387184 5291536 13086304
23:28:25 6370 8192000000 10222494272 10629804032 1308531696 13.773 99 40 78 5 1323136032 5527760 9076576
23:29:33 7469 8192000000 10178275792 10588905472 1261128320 13.340 97 24 48 0 1277351984 10922880 5300784
23:30:45 12426 8192000000 10159283424 10545078272 1195019408 12.730 94 18 36 1 1204368240 5127584 4221248
23:31:57 23846 8192000000 10184117392 10570498048 1257459232 13.307 99 67 133 5 1279960496 5900432 16600832
23:33:21 9747 8192000000 10164317232 10571935744 1266652016 13.391 99 24 48 2 1277698064 5665024 5381024
23:34:31 7580 8192000000 10180801056 10591531008 1286664912 13.574 100 22 43 1 1297257488 5444480 5148096
23:35:40 63772 8192000000 10196007040 10613723136 1302958768 13.722 100 27 54 1 1314686688 5474560 6253360
23:37:49 9764 8192000000 10205309152 10624774144 1318165312 13.860 100 28 56 1 1331130112 6098144 6866656
23:39:08 38156 8192000000 10267685632 10675294208 1349603408 14.144 100 52 105 1 1370433824 9217568 11612848
23:40:51 47381 8192000000 10284319024 10704605184 1371321904 14.339 100 36 70 3 1395747808 16344080 8081824
23:42:40 20998 8192000000 10303131168 10723704832 1386879328 14.478 100 49 96 2 1415839120 17392368 11567424
23:44:11 16091 8192000000 10308745792 10734899200 1393599952 14.538 100 48 94 0 1426394416 21721056 11073408
23:45:32 15553 8192000000 10336364400 10758967296 1407275440 14.660 100 53 106 0 1443890416 24009360 12605616
23:46:53 17089 8192000000 10350333808 10778157056 1419315808 14.767 100 50 99 0 1463129696 30920672 12893216
23:48:18 8576 8192000000 10369063728 10789511168 1426118336 14.827 100 60 111 3 1471940048 32273456 13548256
23:49:33 2050 8192000000 10398822448 10815524864 1442344672 14.970 100 83 166 1 1505624000 42786864 20492464
23:50:38 4798 8192000000 10381907312 10801291264 1413409520 14.714 99 73 144 1 1476797024 45535360 17852144
23:51:47 2863 8192000000 10393308944 10806075392 1416788864 14.744 99 86 170 2 1487175280 49014464 21371952
23:52:54 1865 8192000000 10390324640 10801434624 1419362192 14.767 100 91 182 0 1493963520 51995888 22605440
23:54:03 2332 8192000000 10380083632 10787794944 1385835216 14.469 98 91 180 0 1467522032 58361264 23325552
23:55:11 9421 8192000000 10364288512 10788339712 1398817472 14.584 100 63 125 0 1479237360 63642480 16777408
23:56:27 3433 8192000000 10406940176 10821005312 1422131712 14.792 100 78 150 0 1505594592 63560864 19902016
23:57:34 9894 8192000000 10360288624 10794905600 1382663984 14.440 97 37 74 0 1446300832 53321776 10315072
23:58:58 7220 8192000000 10371639888 10799349760 1398498912 14.582 99 43 86 0 1469595152 58833312 12262928
00:00:11 10565 8192000000 10378966016 10814021632 1407130624 14.658 100 43 86 1 1483696208 64421040 12144544
00:01:27 28975 8192000000 10342499712 10785755136 1381044720 14.426 99 31 62 0 1442005776 51874912 9086144
00:02:58 38252 8192000000 10303007936 10755547136 1359750336 14.235 99 20 40 1 1414468000 49172544 5545120
00:04:41 57167 8192000000 10260327696 10695720960 1323150672 13.905 96 15 28 0 1334934416 8877136 2906608
00:06:54 54035 8192000000 10271189136 10721927168 1352148784 14.167 99 14 28 1 1367114976 12354272 2611920
00:09:03 95775 8192000000 10281503440 10742910976 1353305376 14.177 98 21 43 0 1372349456 13867680 5176400
00:11:39 162150 8192000000 10276746080 10712375296 1282337568 13.534 92 29 58 1 1298592928 9644592 6610768
00:15:24 23499 8192000000 10248212272 10692587520 1304902352 13.740 96 27 54 0 1328403488 16696288 6804848
00:16:54 39227 8192000000 10282374400 10722152448 1360017504 14.238 100 48 96 2 1394697088 23172208 11507376
00:18:38 37127 8192000000 10252035904 10707247104 1347651568 14.126 99 21 42 0 1367941072 15102480 5187024
00:20:16 118161 8192000000 10285135936 10733064192 1374070832 14.364 99 19 37 1 1387635376 8903200 4661344
00:23:14 37465 8192000000 10290355920 10754801664 1390739168 14.512 100 15 30 2 1407957968 13538128 3680672
00:24:57 104321 8192000000 10295651808 10742247424 1369924544 14.326 98 14 28 0 1386613584 13404336 3284704
00:27:42 41932 8192000000 10331677968 10780921856 1410737808 14.690 100 30 58 1 1433399456 15330896 7330752
00:29:29 117907 8192000000 10295317328 10750963712 1331843376 13.984 95 10 20 0 1351475440 17487040 2145024
00:32:34 62856 8192000000 10315944848 10752544768 1374106160 14.364 98 33 65 0 1393919696 12296672 7516864
00:34:38 51839 8192000000 10309890944 10760495104 1387940992 14.487 100 24 48 1 1405051760 11812464 5298304
00:36:36 80126 8192000000 10287899616 10744414208 1371216960 14.338 98 22 43 1 1385496048 9401504 4877584
00:39:52 78619 8192000000 10305207408 10754076672 1375308544 14.375 98 29 58 0 1395241648 13578720 6354384
00:42:14 81930 8192000000 10284422608 10735243264 1356207408 14.203 98 21 42 0 1378206752 16957792 5041552
00:44:36 54960 8192000000 10232189760 10681929728 1278113152 13.496 94 7 14 0 1292883376 13448976 1321248
00:46:42 50716 8192000000 10277021824 10724569088 1363504448 14.269 100 15 30 0 1375147696 8216432 3426816
00:48:50 73595 8192000000 10278532768 10724098048 1325330608 13.925 96 22 44 1 1345715904 14717056 5668240
00:51:06 46134 8192000000 10262509712 10691067904 1308818336 13.775 96 37 74 0 1326439168 8952816 8668016
00:52:55 102566 8192000000 10268131200 10734006272 1351417632 14.160 99 21 42 1 1377442288 21200544 4824112
00:55:39 26516 8192000000 10290821264 10725842944 1374324752 14.366 100 45 89 1 1398695984 13114032 11257200
00:57:10 55081 8192000000 10284792928 10742104064 1375850976 14.379 100 13 26 1 1389320208 10726176 2743056
00:59:19 20626 8192000000 10293488832 10737647616 1374307856 14.366 100 36 70 2 1395574432 13472304 7794272
01:00:40 98289 8192000000 10267315712 10723393536 1360212816 14.239 99 19 38 0 1379728560 15194656 4321088
01:03:41 30723 8192000000 10282699888 10721329152 1358939200 14.228 99 30 60 1 1377456656 11752592 6764864
01:05:17 70124 8192000000 10249587888 10700427264 1294296000 13.643 94 7 14 0 1305000240 9028944 1675296
01:07:29 55527 8192000000 10241632368 10690269184 1317884304 13.858 97 26 52 0 1332836304 8546592 6405408
01:09:28 3478 8192000000 10266463184 10703835136 1358314368 14.222 100 61 124 5 1375932384 3853584 13764432
01:10:32 84043 8192000000 10274344400 10740768768 1374196080 14.365 100 7 14 0 1385374112 9424208 1753824
01:12:57 55165 8192000000 10272181920 10715234304 1338679056 14.045 98 15 30 1 1350252960 7878880 3695024
01:14:54 41398 8192000000 10279911520 10718334976 1335385152 14.016 97 24 47 1 1348043648 7778720 4879776
01:16:36 40376 8192000000 10271852992 10723188736 1339398512 14.052 97 20 40 0 1353181568 9072672 4710384
01:18:46 66665 8192000000 10286776000 10728931328 1336647984 14.027 97 20 40 2 1350629440 9569680 4411776
01:21:11 107968 8192000000 10290633872 10753003520 1369087616 14.319 99 14 27 1 1389164272 16785728 3290928
01:23:59 119184 8192000000 10279493264 10731024384 1357043616 14.211 98 12 24 0 1372314800 12550608 2720576
01:27:04 107720 8192000000 10271291024 10719129600 1288591072 13.591 93 21 41 1 1305504064 12284608 4628384
01:29:55 72842 8192000000 10271228448 10732646400 1377355488 14.393 100 11 22 2 1391556816 11850864 2350464
01:32:08 56110 8192000000 10270650096 10720645120 1333025328 13.994 97 9 18 0 1342876848 7996832 1854688
01:34:04 67839 8192000000 10286083456 10732249088 1372052928 14.345 100 19 38 0 1386432208 9887184 4492096
01:36:19 79249 8192000000 10270914672 10730827776 1363862768 14.272 99 19 38 0 1376881408 8813312 4205328

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-12-2021 01:40:19 to 11-12-2021 01:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-12-2021 01:40:35 to 11-12-2021 01:40:37.

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          235598
MON$OLDEST_ACTIVE               236085
MON$OLDEST_SNAPSHOT             236085
MON$NEXT_TRANSACTION            236085
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                       2015104
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.33542 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33542 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33542 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33542 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 ---
:225: 
:226: mscs-test-161.budzdorov.ru    Sat Dec 11 01:38:01 2021
:227:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:228: 
:229: 
:230: mscs-test-161.budzdorov.ru    Sat Dec 11 01:38:04 2021
:231:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:232: 
:233: 
:234: mscs-test-161.budzdorov.ru    Sat Dec 11 01:38:12 2021
:235:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:236: 
:237: 
:238: mscs-test-161.budzdorov.ru    Sat Dec 11 01:38:17 2021
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:240: 
:241: 
:242: mscs-test-161.budzdorov.ru    Sat Dec 11 01:38:17 2021
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:244: 
--- end of diff output ---

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

11.12.21 01:40:38. 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-12-11 01:40:41 No severe PSQL-related problems occured
11.12.21 01:40:42. End of report.