Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2021 16: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 16649756 kB
MemAvailable 31369432 kB
Buffers 0 kB
Cached 15001908 kB
SwapCached 444 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-22 16:38:01
DTS_END                         2021-12-22 16:38:01.6030
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/59952
TRN_ID                          246560
ATT_ID                          661
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: 9279.73 24091 1113567
customer order: creation 179.33 379 21520
customer order: refuse 15.36 379 1843
order to supplier: creation 47.43 1482 5692
order to supplier: removal 3.24 104107 389
invoice (draft): creation 47.75 3616 5730
invoice (draft): removal 51.16 911 6139
invoice accept: apply 43.81 19865 5257
invoice accept: cancel 45.43 849 5451
customer reserve: creation 4049.93 197 485991
customer reserve: removal 4498.17 57 539780
realization accept: apply 87.86 191 10543
realization accept: cancel 73.13 115 8776
payment from customer: creation 61.58 119 7390
payment from customer: removal 6.23 90 748
payment to supplier: creation 52.68 122 6321
payment to supplier: removal 3.82 104 458
service: total inventory turnovers 4.82 1199 578
service: total monetary turnovers 7.11 31 853
service: aggregate perf. data 0.87 19126 104
service: refresh index statistics 0.03 328884 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-12-2021 16:38:49 to 22-12-2021 16:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4870
TEST_TIME 2 4319
TEST_TIME 3 5235
TEST_TIME 4 5394
TEST_TIME 5 5718
TEST_TIME 6 6088
TEST_TIME 7 6578
TEST_TIME 8 6350
TEST_TIME 9 6663
TEST_TIME 10 7060
TEST_TIME 11 6853
TEST_TIME 12 7146
TEST_TIME 13 7154
TEST_TIME 14 7472
TEST_TIME 15 7560
TEST_TIME 16 7458
TEST_TIME 17 7592
TEST_TIME 18 7904
TEST_TIME 19 8603
TEST_TIME 20 8838
TEST_TIME 21 9352
TEST_TIME 22 9384
TEST_TIME 23 9305
TEST_TIME 24 9323
TEST_TIME 25 9750
TEST_TIME 26 9935
TEST_TIME 27 10878
TEST_TIME 28 10749
TEST_TIME 29 10606
TEST_TIME 30 10442
TEST_TIME 31 10428
TEST_TIME 32 10182
TEST_TIME 33 10325
TEST_TIME 34 10164
TEST_TIME 35 10036
TEST_TIME 36 9983
TEST_TIME 37 9800
TEST_TIME 38 9887
TEST_TIME 39 9755
TEST_TIME 40 9745
TEST_TIME 41 9741
TEST_TIME 42 9620
TEST_TIME 43 9807
TEST_TIME 44 9867
TEST_TIME 45 9820
TEST_TIME 46 9773
TEST_TIME 47 9794
TEST_TIME 48 9708
TEST_TIME 49 9702
TEST_TIME 50 9815
TEST_TIME 51 9863
TEST_TIME 52 9941
TEST_TIME 53 9943
TEST_TIME 54 9969
TEST_TIME 55 10015
TEST_TIME 56 9880
TEST_TIME 57 9748
TEST_TIME 58 9797
TEST_TIME 59 9754
TEST_TIME 60 9659
TEST_TIME 61 9626
TEST_TIME 62 9675
TEST_TIME 63 9632
TEST_TIME 64 9584
TEST_TIME 65 9508
TEST_TIME 66 9508
TEST_TIME 67 9493
TEST_TIME 68 9486
TEST_TIME 69 9488
TEST_TIME 70 9531
TEST_TIME 71 9525
TEST_TIME 72 9560
TEST_TIME 73 9546
TEST_TIME 74 9628
TEST_TIME 75 9595
TEST_TIME 76 9614
TEST_TIME 77 9665
TEST_TIME 78 9632
TEST_TIME 79 9566
TEST_TIME 80 9505
TEST_TIME 81 9471
TEST_TIME 82 9429
TEST_TIME 83 9417
TEST_TIME 84 9408
TEST_TIME 85 9399
TEST_TIME 86 9325
TEST_TIME 87 9335
TEST_TIME 88 9303
TEST_TIME 89 9283
TEST_TIME 90 9382
TEST_TIME 91 9379
TEST_TIME 92 9484
TEST_TIME 93 9474
TEST_TIME 94 9505
TEST_TIME 95 9498
TEST_TIME 96 9499
TEST_TIME 97 9553
TEST_TIME 98 9582
TEST_TIME 99 9550
TEST_TIME 100 9604
TEST_TIME 101 9602
TEST_TIME 102 9519
TEST_TIME 103 9502
TEST_TIME 104 9466
TEST_TIME 105 9446
TEST_TIME 106 9412
TEST_TIME 107 9379
TEST_TIME 108 9345
TEST_TIME 109 9336
TEST_TIME 110 9321
TEST_TIME 111 9291
TEST_TIME 112 9309
TEST_TIME 113 9300
TEST_TIME 114 9331
TEST_TIME 115 9310
TEST_TIME 116 9347
TEST_TIME 117 9339
TEST_TIME 118 9324
TEST_TIME 119 9356
TEST_TIME 120 9281

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-12-2021 16:38:52 to 22-12-2021 16:38:56.

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 1560983 1560983 0 0.00 0 20071 7 0 0
doc_list_biud 1560943 1560943 0 0.00 0 51724 26 0 0
sp_add_invoice_to_stock 5378 5257 121 2.24 34 160414 19865 0 121
sp_cancel_adding_invoice 5451 5451 0 0.00 6 49816 850 0 0
sp_cancel_client_order 1843 1843 0 0.00 5 19561 379 0 0
sp_cancel_customer_reserve 539780 539780 0 0.00 1 51725 58 0 0
sp_cancel_pay_from_customer 748 748 0 0.00 3 4721 91 0 0
sp_cancel_pay_to_supplier 458 458 0 0.00 3 5648 105 0 0
sp_cancel_supplier_invoice 6151 6139 12 0.19 2 13604 911 0 12
sp_cancel_supplier_order 389 389 0 0.00 6 1064777 104107 0 0
sp_cancel_write_off 8776 8776 0 0.00 1 19974 115 0 0
sp_client_order 21520 21520 0 0.00 3 43306 380 0 0
sp_customer_reserve 485991 485991 0 0.00 0 31918 197 0 0
sp_fill_shopping_cart 32942 32942 0 0.00 1 9768 43 0 0
sp_fill_shopping_cart_clo_res 485989 485989 0 0.00 0 18246 14 0 0
sp_get_clo_for_invoice 5255 5255 0 0.00 0 40573 276 0 0
sp_kill_cost_storno 15433 15433 0 0.00 0 11145 55 0 0
sp_kill_qstorno_ret_qs2qd 1094864 1094864 0 0.00 0 50564 32 0 0
sp_kill_qty_storno 578152 578152 0 0.00 0 51723 70 0 0
sp_lock_selected_doc 21884 21884 0 0.00 0 9501 8 0 0
sp_make_cost_storno 29509 29509 0 0.00 0 15607 54 0 0
sp_multiply_rows_for_pdistr 29469 29469 0 0.00 0 6741 32 0 0
sp_multiply_rows_for_qdistr 21520 21520 0 0.00 0 43299 175 0 0
sp_pay_from_customer 7390 7390 0 0.00 4 16954 120 0 0
sp_pay_to_supplier 6321 6321 0 0.00 4 13735 123 0 0
sp_payment_common 13695 13695 0 0.00 1 10327 24 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 0 19553 341 0 0
sp_qd_handle_on_invoice_upd_sts 10697 10697 0 0.00 0 21425 345 0 0
sp_qd_handle_on_reserve_upd_sts 10528 10528 0 0.00 0 21084 57 0 0
sp_reserve_write_off 10543 10543 0 0.00 6 21127 192 0 0
sp_supplier_invoice 5730 5730 0 0.00 5 100327 3617 0 0
sp_supplier_order 5692 5692 0 0.00 6 59897 1483 0 0
srv_aggregate_perf_data 104 104 0 0.00 2952 282917 19127 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1190 118852 43372 0 0
srv_find_qd_qs_mism 1062097 1062097 0 0.00 0 20069 6 0 0
srv_make_invnt_saldo 578 578 0 0.00 35 87916 1200 0 0
srv_make_money_saldo 853 853 0 0.00 1 1646 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 154715 462948 328884 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5257 5257 0 0.00 0 8736 172 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 1 7463 121 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 6790 13 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 493 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 748 748 0 0.00 0 2187 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 458 458 0 0.00 0 843 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 694 13 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 31143 1604 0 0
t$perf-norm:sp_cancel_write_off 1049 1049 0 0.00 0 5856 21 0 0
t$perf-norm:sp_client_order 21520 21520 0 0.00 0 8890 18 0 0
t$perf-norm:sp_customer_reserve 1073 1073 0 0.00 0 4543 11 0 0
t$perf-norm:sp_pay_from_customer 7390 7390 0 0.00 0 7920 10 0 0
t$perf-norm:sp_pay_to_supplier 6321 6321 0 0.00 0 4878 10 0 0
t$perf-norm:sp_reserve_write_off 10543 10543 0 0.00 0 6672 9 0 0
t$perf-norm:sp_supplier_invoice 5730 5730 0 0.00 0 6304 17 0 0
t$perf-norm:sp_supplier_order 5692 5692 0 0.00 0 5723 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 303 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 162 5 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 2691 30 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 637 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10734 10492 242 2.25 0 5642 22 0 242
v_all_customers 38852 38852 0 0.00 0 19 1 0 0
v_all_suppliers 31528 31528 0 0.00 0 16 1 0 0
v_all_wares 43040 43040 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 1289 11 0 0
v_cancel_client_order 3686 3686 0 0.00 0 4822 8 0 0
v_cancel_customer_prepayment 1496 1496 0 0.00 0 3776 8 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3747 10 0 0
v_cancel_payment_to_supplier 916 916 0 0.00 0 4821 11 0 0
v_cancel_supplier_invoice 898 874 24 2.67 0 1791 16 0 24
v_cancel_supplier_order 778 778 0 0.00 0 4677 14 0 0
v_cancel_write_off 2098 2098 0 0.00 0 4527 20 0 0
v_max_id_clo_ord 5692 5692 0 0.00 0 2234 2 0 0
v_max_id_clo_res 1073 1073 0 0.00 0 3428 15 0 0
v_max_id_ord_sup 5730 5730 0 0.00 0 3745 5 0 0
v_max_non_paid_invoice 1970 1970 0 0.00 0 5960 6 0 0
v_max_non_paid_realizn 4137 4137 0 0.00 0 1763 4 0 0
v_min_id_clo_ord 5692 5692 0 0.00 0 7986 13 0 0
v_min_id_clo_res 1073 1073 0 0.00 0 7176 26 0 0
v_min_id_ord_sup 5730 5730 0 0.00 0 6960 14 0 0
v_min_non_paid_invoice 6312 6312 0 0.00 0 4945 11 0 0
v_min_non_paid_realizn 7383 7383 0 0.00 0 5965 16 0 0
v_our_firm 10680 10680 0 0.00 0 16 1 0 0
v_reserve_write_off 21056 21056 0 0.00 0 5121 8 0 0
x_lock_depdocs_on_canc_invoice 11153 11153 0 0.00 0 10448 117 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 10635 353 0 0
x_make_qstorno_1000_1200 5692 5692 0 0.00 1 59879 1386 0 0
x_make_qstorno_1200_2000 5730 5730 0 0.00 2 100313 3468 0 0
x_make_qstorno_2100_3300 485990 485990 0 0.00 0 31081 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-12-2021 16:38:57 to 22-12-2021 16:39:01.

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
14:08:05 14 8192000000 8804740096 8817971200 9028768 0.110 4 1 2 0 9131056 59504 42784
14:09:06 23333 8192000000 9906842752 10221092864 991829120 10.799 100 84 170 0 1020035040 6001888 22204032
14:10:38 23868 8192000000 9952444832 10276352000 1033160368 11.199 100 89 178 0 1064540704 8243120 23137216
14:12:10 8413 8192000000 9987683696 10319626240 1074191760 11.592 100 81 162 0 1103907088 9468784 20246544
14:13:23 13805 8192000000 10033834272 10379051008 1121712032 12.043 100 82 164 1 1151390592 10689968 18988592
14:14:44 10443 8192000000 10082544832 10438692864 1166057504 12.460 100 82 164 1 1197509232 12016832 19434896
14:15:56 6811 8192000000 10126123056 10494906368 1214575936 12.911 100 78 156 0 1244916624 13091536 17249152
14:17:04 2614 8192000000 10169202160 10541297664 1246833104 13.209 100 83 165 0 1279506368 14637552 18035712
14:18:09 2444 8192000000 10197436832 10580992000 1282663760 13.537 100 94 190 1 1318252288 14639168 20949360
14:19:13 18980 8192000000 10201822240 10613030912 1308128912 13.769 100 66 133 0 1336536720 13678512 14729296
14:20:37 5068 8192000000 10198781152 10614870016 1312457104 13.808 100 33 66 1 1327629264 7922016 7250144
14:21:46 5522 8192000000 10217477248 10628894720 1320981792 13.886 100 41 83 1 1338229088 8265840 8981456
14:22:54 8001 8192000000 10223691184 10641809408 1328278272 13.952 100 16 32 0 1337777616 6038832 3460512
14:24:04 63467 8192000000 10229736064 10655051776 1336853648 14.029 100 38 76 1 1351982880 5743216 9386016
14:26:10 8600 8192000000 10231223552 10653949952 1338838848 14.047 100 26 52 1 1350595696 5588064 6168784
14:27:19 6695 8192000000 10228980128 10650779648 1335433392 14.016 100 21 42 2 1346198480 6197744 4567344
14:28:29 69563 8192000000 10147782016 10554761216 1146940224 12.281 88 40 78 2 1160706336 4601232 9164880
14:30:39 7060 8192000000 10140081728 10544500736 1221804640 12.978 96 21 42 0 1233109552 6633536 4671376
14:31:47 13016 8192000000 10138733632 10533203968 1221797808 12.978 98 22 43 1 1232597008 5439520 5359680
14:33:01 53522 8192000000 10140697792 10545344512 1223375728 12.993 97 33 66 2 1236724048 5643632 7704688
14:35:05 5946 8192000000 10182772192 10584297472 1281842464 13.530 100 39 78 1 1296585984 5600576 9142944
14:36:13 5838 8192000000 10183639280 10596343808 1292413504 13.626 100 23 46 0 1303409040 5355808 5639728
14:37:23 6826 8192000000 10186229568 10603118592 1301219920 13.706 100 16 32 1 1310686992 5913360 3553712
14:38:35 61263 8192000000 10242283296 10649051136 1328121120 13.950 100 61 121 0 1353747520 11072784 14553616
14:40:41 19179 8192000000 10250461504 10663350272 1342222112 14.077 100 46 92 0 1365066864 12573856 10270896
14:42:05 7046 8192000000 10272573856 10681114624 1354977552 14.192 100 61 122 1 1384686576 15380544 14328480
14:43:17 14818 8192000000 10267526640 10690674688 1362812912 14.263 100 35 70 1 1385859856 15162880 7884064
14:44:37 11743 8192000000 10285530752 10706673664 1361515712 14.251 99 57 115 1 1396804432 22418416 12870304
14:45:58 5155 8192000000 10308980480 10728603648 1388906960 14.496 100 59 118 1 1421040432 18260992 13872480
14:47:12 2673 8192000000 10317296000 10733637632 1392099696 14.525 100 63 126 1 1432507632 25424224 14983712
14:48:17 1595 8192000000 10318715760 10730033152 1371078960 14.337 98 74 147 1 1413941968 25380752 17482256
14:49:22 11125 8192000000 10310182208 10727145472 1374800736 14.370 99 56 109 0 1411921536 24227456 12893344
14:50:43 1187 8192000000 10339132480 10751012864 1393900016 14.541 100 78 149 3 1437755264 25663712 18191536
14:51:46 1619 8192000000 10351431440 10762469376 1405231104 14.642 100 89 175 1 1456472416 30024400 21216912
14:52:51 4567 8192000000 10326395520 10740830208 1373048864 14.354 99 68 136 1 1420036224 30483504 16503856
14:54:02 9208 8192000000 10322713968 10732396544 1383111216 14.444 100 69 136 1 1432409632 32622032 16676384
14:55:17 1732 8192000000 10315792384 10715136000 1350866000 14.155 99 84 166 0 1414449904 41900752 21683152
14:56:25 1357 8192000000 10355099728 10755334144 1383177968 14.445 100 87 175 0 1455236176 49738768 22319440
14:57:30 8816 8192000000 10320131888 10746253312 1375566832 14.377 100 52 102 0 1435229456 45519296 14143328
14:58:44 1527 8192000000 10340394736 10753818624 1385470736 14.465 100 90 178 1 1448351296 39684336 23196224
14:59:52 22508 8192000000 10298486512 10727911424 1366499104 14.296 99 38 75 1 1409541392 32472064 10570224
15:01:21 4450 8192000000 10314754896 10731945984 1375925552 14.380 100 52 104 0 1416002000 26868992 13207456
15:02:34 6511 8192000000 10306962832 10727772160 1349387056 14.142 99 53 103 0 1398327472 34844704 14095712
15:03:44 24546 8192000000 10274200896 10712035328 1328440560 13.953 98 26 52 0 1367261344 31415264 7405520
15:05:18 30486 8192000000 10269030688 10717151232 1360929456 14.246 100 17 34 0 1402505584 36674896 4901232
15:07:08 33400 8192000000 10289451296 10721132544 1372298944 14.348 100 32 64 2 1390332736 10884144 7149648
15:08:43 118852 8192000000 10298312144 10742882304 1385494336 14.466 100 20 40 0 1406920672 17117248 4309088
15:11:47 89650 8192000000 10274771088 10730098688 1368642416 14.315 99 23 46 0 1384759312 11032576 5084320
15:14:49 30742 8192000000 10318970032 10768990208 1394431392 14.545 100 22 42 1 1413118512 13735280 4951840
15:16:21 55208 8192000000 10320404416 10786443264 1402964144 14.621 100 21 42 0 1430812304 22617856 5230304
15:19:43 54367 8192000000 10315463952 10770456576 1390451648 14.510 99 12 24 0 1413338304 19803200 3083456
15:22:31 29218 8192000000 10300383472 10762936320 1384061568 14.453 99 21 43 3 1401558512 12810800 4686144
15:24:07 37135 8192000000 10314816896 10760986624 1396832496 14.567 100 29 58 0 1413476640 9651088 6993056
15:25:58 68216 8192000000 10311063248 10766196736 1377556704 14.395 98 31 60 0 1399944464 14941088 7446672
15:28:48 45177 8192000000 10295706640 10767114240 1383677744 14.449 99 12 22 1 1403550480 17138352 2734384
15:30:35 55359 8192000000 10283321792 10736627712 1333880800 14.002 96 11 23 1 1352143344 15555632 2706912
15:32:42 109724 8192000000 10276065952 10723622912 1329913712 13.966 96 31 62 2 1348820624 11622800 7284112
15:35:33 86102 8192000000 10278021504 10733117440 1362523744 14.260 99 20 40 2 1376977984 10325024 4129216
15:38:10 32811 8192000000 10299514896 10735677440 1368519536 14.314 99 44 89 1 1389389536 10964496 9905504
15:39:44 52103 8192000000 10266531072 10723880960 1359105232 14.229 99 33 66 3 1380829264 13524928 8199104
15:41:41 69260 8192000000 10241492752 10698465280 1310039248 13.786 96 12 24 0 1326693760 14021488 2633024
15:43:50 72974 8192000000 10252357776 10699382784 1319315008 13.871 97 9 18 0 1330250480 9174208 1761264
15:46:03 15953 8192000000 10259312448 10699382784 1328745408 13.956 98 29 57 2 1342138272 7381664 6011200
15:47:26 78013 8192000000 10268930336 10720563200 1302211952 13.715 95 14 28 0 1321275376 15703664 3359760
15:49:45 77562 8192000000 10258039024 10704863232 1311025776 13.795 97 9 18 0 1337104368 24511568 1567024
15:52:03 96865 8192000000 10261926512 10701668352 1320434848 13.881 97 19 38 1 1335231408 10518784 4277776
15:54:40 64594 8192000000 10257832208 10709647360 1332923600 13.994 98 22 44 1 1350218144 12461120 4833424
15:57:25 110135 8192000000 10273172720 10701385728 1352831088 14.173 100 45 90 1 1372830752 9617648 10382016
16:00:19 17809 8192000000 10256353408 10707709952 1339330464 14.051 98 18 37 1 1355367216 12236480 3800272
16:01:38 67964 8192000000 10260254192 10730483712 1367888416 14.308 100 6 12 1 1379837280 10662688 1286176
16:03:47 72735 8192000000 10257047360 10717990912 1355638480 14.198 99 6 12 0 1365582176 8665264 1278432
16:06:26 74369 8192000000 10303397488 10760671232 1373722224 14.360 99 19 38 1 1395964480 17624304 4617952
16:09:25 50693 8192000000 10298064160 10755334144 1385965584 14.470 100 10 20 0 1405817392 17130304 2721504
16:11:27 27209 8192000000 10293141968 10739740672 1387566960 14.484 100 25 50 2 1400009088 7079552 5362576
16:12:56 63311 8192000000 10283020448 10720542720 1326803408 13.938 96 31 59 0 1344647664 10708336 7135920
16:15:09 59633 8192000000 10304661328 10742165504 1385844624 14.469 100 23 46 1 1410175264 18866592 5464048
16:17:15 29536 8192000000 10299797376 10733182976 1365455440 14.286 99 34 68 0 1381575344 8856240 7263664
16:18:45 72541 8192000000 10296056752 10749124608 1373789040 14.361 99 15 28 1 1389945984 12670192 3486752
16:21:21 62756 8192000000 10276650496 10730016768 1358288496 14.222 98 19 38 0 1369837008 7570144 3978368
16:23:25 62345 8192000000 10260641904 10719612928 1361552176 14.251 99 6 12 1 1373419904 10279760 1587968
16:25:28 39813 8192000000 10281977040 10733395968 1360894576 14.245 99 7 14 0 1378983440 16432800 1656064
16:27:13 66199 8192000000 10268494080 10734395392 1342249696 14.078 97 13 26 0 1356033456 10735184 3048576
16:29:28 69817 8192000000 10272053696 10729996288 1324724880 13.919 96 20 39 0 1338078160 8726224 4627056
16:31:42 56928 8192000000 10278136736 10715869184 1346197056 14.113 99 22 44 1 1360691392 9265376 5228960
16:33:43 62570 8192000000 10239929712 10689015808 1295000624 13.650 96 14 28 1 1310883104 12778896 3103584
16:35:47 24705 8192000000 10264256400 10695905280 1328654208 13.955 99 31 62 0 1344213920 8788688 6771024
16:37:14 58280 8192000000 9251342432 9522810880 390505440 4.550 29 6 12 0 400497872 8691120 1301312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-12-2021 16:39:03 to 22-12-2021 16:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-12-2021 16:39:18 to 22-12-2021 16: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          246821
MON$OLDEST_ACTIVE               246822
MON$OLDEST_SNAPSHOT             246822
MON$NEXT_TRANSACTION            246822
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                       2022080
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.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 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 ---
:270: 
:271: mscs-test-161.budzdorov.ru    Wed Dec 22 16:38:17 2021
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:273: 
--- end of diff output ---

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

22.12.21 16: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
2021-12-22 16:39:24 No severe PSQL-related problems occured
22.12.21 16:39:25. End of report.