Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.04.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 17061640 kB
MemAvailable 31492396 kB
Buffers 0 kB
Cached 14626900 kB
SwapCached 2688 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.10
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-04-15 01:38:11
DTS_END                         2022-04-15 01:38:11.2390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59018
TRN_ID                          247698
ATT_ID                          703
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: 9440.70 19654 1132884
customer order: creation 184.13 323 22096
customer order: refuse 15.97 331 1916
order to supplier: creation 47.80 1228 5736
order to supplier: removal 3.58 90678 429
invoice (draft): creation 47.97 3291 5756
invoice (draft): removal 53.46 736 6415
invoice accept: apply 43.15 18194 5178
invoice accept: cancel 48.08 811 5770
customer reserve: creation 3979.41 181 477529
customer reserve: removal 4701.08 53 564130
realization accept: apply 88.30 179 10596
realization accept: cancel 91.18 108 10942
payment from customer: creation 62.58 118 7509
payment from customer: removal 6.29 59 755
payment to supplier: creation 51.30 124 6156
payment to supplier: removal 3.86 87 463
service: total inventory turnovers 5.01 1002 601
service: total monetary turnovers 6.63 65 795
service: aggregate perf. data 0.90 21273 108
service: refresh index statistics 0.03 254238 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-04-2022 01:38:59 to 15-04-2022 01:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9738
TEST_TIME 2 7551
TEST_TIME 3 7097
TEST_TIME 4 9026
TEST_TIME 5 9077
TEST_TIME 6 8321
TEST_TIME 7 8282
TEST_TIME 8 8266
TEST_TIME 9 8234
TEST_TIME 10 7980
TEST_TIME 11 7661
TEST_TIME 12 7478
TEST_TIME 13 7464
TEST_TIME 14 7319
TEST_TIME 15 7634
TEST_TIME 16 7686
TEST_TIME 17 7744
TEST_TIME 18 7778
TEST_TIME 19 8038
TEST_TIME 20 7978
TEST_TIME 21 7969
TEST_TIME 22 8091
TEST_TIME 23 8271
TEST_TIME 24 8439
TEST_TIME 25 8592
TEST_TIME 26 8844
TEST_TIME 27 8966
TEST_TIME 28 9252
TEST_TIME 29 9654
TEST_TIME 30 9679
TEST_TIME 31 9754
TEST_TIME 32 10335
TEST_TIME 33 10448
TEST_TIME 34 10680
TEST_TIME 35 10604
TEST_TIME 36 10436
TEST_TIME 37 10281
TEST_TIME 38 10154
TEST_TIME 39 10104
TEST_TIME 40 10222
TEST_TIME 41 10446
TEST_TIME 42 10307
TEST_TIME 43 10335
TEST_TIME 44 10149
TEST_TIME 45 10010
TEST_TIME 46 10010
TEST_TIME 47 9915
TEST_TIME 48 10013
TEST_TIME 49 9915
TEST_TIME 50 9939
TEST_TIME 51 10023
TEST_TIME 52 9938
TEST_TIME 53 9772
TEST_TIME 54 9749
TEST_TIME 55 9716
TEST_TIME 56 9649
TEST_TIME 57 9574
TEST_TIME 58 9488
TEST_TIME 59 9470
TEST_TIME 60 9432
TEST_TIME 61 9493
TEST_TIME 62 9489
TEST_TIME 63 9512
TEST_TIME 64 9515
TEST_TIME 65 9597
TEST_TIME 66 9634
TEST_TIME 67 9620
TEST_TIME 68 9645
TEST_TIME 69 9625
TEST_TIME 70 9663
TEST_TIME 71 9735
TEST_TIME 72 9737
TEST_TIME 73 9816
TEST_TIME 74 9833
TEST_TIME 75 9744
TEST_TIME 76 9684
TEST_TIME 77 9691
TEST_TIME 78 9637
TEST_TIME 79 9590
TEST_TIME 80 9542
TEST_TIME 81 9486
TEST_TIME 82 9475
TEST_TIME 83 9402
TEST_TIME 84 9403
TEST_TIME 85 9529
TEST_TIME 86 9538
TEST_TIME 87 9502
TEST_TIME 88 9509
TEST_TIME 89 9562
TEST_TIME 90 9590
TEST_TIME 91 9601
TEST_TIME 92 9578
TEST_TIME 93 9697
TEST_TIME 94 9737
TEST_TIME 95 9738
TEST_TIME 96 9720
TEST_TIME 97 9738
TEST_TIME 98 9779
TEST_TIME 99 9694
TEST_TIME 100 9621
TEST_TIME 101 9623
TEST_TIME 102 9663
TEST_TIME 103 9624
TEST_TIME 104 9615
TEST_TIME 105 9590
TEST_TIME 106 9545
TEST_TIME 107 9542
TEST_TIME 108 9510
TEST_TIME 109 9477
TEST_TIME 110 9441
TEST_TIME 111 9454
TEST_TIME 112 9455
TEST_TIME 113 9442
TEST_TIME 114 9442
TEST_TIME 115 9437
TEST_TIME 116 9437
TEST_TIME 117 9531
TEST_TIME 118 9525
TEST_TIME 119 9504
TEST_TIME 120 9442

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-04-2022 01:39:03 to 15-04-2022 01:39:06.

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 1574083 1574083 0 0.00 0 21048 6 0 0
doc_list_biud 1574080 1574080 0 0.00 0 55471 25 0 0
sp_add_invoice_to_stock 5286 5178 108 2.04 31 164289 18194 0 108
sp_cancel_adding_invoice 5770 5770 0 0.00 5 55619 812 0 0
sp_cancel_client_order 1916 1916 0 0.00 4 8790 332 0 0
sp_cancel_customer_reserve 564130 564130 0 0.00 1 40134 54 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 2 3208 59 0 0
sp_cancel_pay_to_supplier 463 463 0 0.00 3 4155 87 0 0
sp_cancel_supplier_invoice 6423 6415 8 0.12 1 49251 737 0 8
sp_cancel_supplier_order 429 429 0 0.00 10 963033 90679 0 0
sp_cancel_write_off 10942 10942 0 0.00 1 7781 109 0 0
sp_client_order 22096 22096 0 0.00 3 31282 324 0 0
sp_customer_reserve 477529 477529 0 0.00 0 91549 181 0 0
sp_fill_shopping_cart 33587 33587 0 0.00 1 13195 33 0 0
sp_fill_shopping_cart_clo_res 477525 477525 0 0.00 0 11584 12 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 30535 233 0 0
sp_kill_cost_storno 17930 17930 0 0.00 0 7777 51 0 0
sp_kill_qstorno_ret_qs2qd 1146046 1146046 0 0.00 0 40129 29 0 0
sp_kill_qty_storno 605372 605372 0 0.00 0 55467 64 0 0
sp_lock_selected_doc 21909 21909 0 0.00 0 6880 6 0 0
sp_make_cost_storno 29435 29435 0 0.00 0 16304 56 0 0
sp_multiply_rows_for_pdistr 29435 29435 0 0.00 0 9214 31 0 0
sp_multiply_rows_for_qdistr 22096 22096 0 0.00 0 31273 147 0 0
sp_pay_from_customer 7509 7509 0 0.00 4 11602 119 0 0
sp_pay_to_supplier 6156 6156 0 0.00 5 16341 125 0 0
sp_payment_common 13665 13665 0 0.00 1 9766 30 0 0
sp_qd_handle_on_cancel_clo 1916 1916 0 0.00 0 8782 306 0 0
sp_qd_handle_on_invoice_upd_sts 10944 10944 0 0.00 0 55463 331 0 0
sp_qd_handle_on_reserve_upd_sts 10596 10596 0 0.00 0 19035 58 0 0
sp_reserve_write_off 10596 10596 0 0.00 6 19046 179 0 0
sp_supplier_invoice 5756 5756 0 0.00 5 104308 3292 0 0
sp_supplier_order 5736 5736 0 0.00 4 34099 1229 0 0
srv_aggregate_perf_data 108 108 0 0.00 2868 186784 21274 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1994 114148 40379 0 0
srv_find_qd_qs_mism 1082287 1082287 0 0.00 0 21044 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 20 53439 1003 0 0
srv_make_money_saldo 795 795 0 0.00 0 17123 65 0 0
srv_recalc_idx_stat 4 4 0 0.00 89205 382764 254239 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5178 5178 0 0.00 0 15440 159 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 3320 103 0 0
t$perf-norm:sp_cancel_client_order 1916 1916 0 0.00 0 3821 14 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 3803 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 2899 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 463 463 0 0.00 0 463 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 706 15 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 20210 1539 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 6695 17 0 0
t$perf-norm:sp_client_order 22096 22096 0 0.00 0 18278 18 0 0
t$perf-norm:sp_customer_reserve 1054 1054 0 0.00 0 1060 4 0 0
t$perf-norm:sp_pay_from_customer 7509 7509 0 0.00 0 8704 10 0 0
t$perf-norm:sp_pay_to_supplier 6156 6156 0 0.00 0 4193 8 0 0
t$perf-norm:sp_reserve_write_off 10596 10596 0 0.00 0 7181 14 0 0
t$perf-norm:sp_supplier_invoice 5756 5756 0 0.00 0 3715 18 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 7354 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 779 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 348 11 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 744 20 0 0
t$perf-norm:srv_make_money_saldo 795 795 0 0.00 0 1640 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10558 10342 216 2.04 0 7384 19 0 216
v_all_customers 40026 40026 0 0.00 0 13 1 0 0
v_all_suppliers 31600 31600 0 0.00 0 12 1 0 0
v_all_wares 44192 44192 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 3375 11 0 0
v_cancel_client_order 3832 3832 0 0.00 0 5511 6 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 3110 7 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 223 3 0 0
v_cancel_payment_to_supplier 926 926 0 0.00 0 4072 10 0 0
v_cancel_supplier_invoice 848 832 16 1.88 0 2919 16 0 16
v_cancel_supplier_order 858 858 0 0.00 0 556 3 0 0
v_cancel_write_off 2040 2040 0 0.00 0 4310 16 0 0
v_max_id_clo_ord 5736 5736 0 0.00 0 265 1 0 0
v_max_id_clo_res 1054 1054 0 0.00 0 4507 16 0 0
v_max_id_ord_sup 5755 5755 0 0.00 0 3996 5 0 0
v_max_non_paid_invoice 1847 1847 0 0.00 0 2281 3 0 0
v_max_non_paid_realizn 4017 4017 0 0.00 0 4405 6 0 0
v_min_id_clo_ord 5736 5736 0 0.00 0 3727 7 0 0
v_min_id_clo_res 1054 1054 0 0.00 0 6754 19 0 0
v_min_id_ord_sup 5755 5755 0 0.00 0 6792 4 0 0
v_min_non_paid_invoice 6156 6156 0 0.00 0 7471 22 0 0
v_min_non_paid_realizn 7509 7509 0 0.00 0 8179 24 0 0
v_our_firm 11150 11150 0 0.00 0 12 1 0 0
v_reserve_write_off 21192 21192 0 0.00 0 5655 8 0 0
x_lock_depdocs_on_canc_invoice 11769 11769 0 0.00 0 21982 111 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 6753 308 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 1 34093 1146 0 0
x_make_qstorno_1200_2000 5756 5756 0 0.00 3 104298 3179 0 0
x_make_qstorno_2100_3300 477529 477529 0 0.00 0 91548 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-04-2022 01:39:07 to 15-04-2022 01:39:11.

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:14 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:16 25950 8192000000 9897742288 10203762688 977463920 10.659 100 84 168 0 1005245184 5767888 22013376
23:10:46 26879 8192000000 9933711680 10251628544 1012806624 11.003 100 91 182 0 1045310688 8834256 23669808
23:12:19 8848 8192000000 9970422784 10299744256 1054638176 11.405 100 87 174 0 1086549440 10007376 21903888
23:13:38 13125 8192000000 10032073136 10381758464 1120976768 12.036 100 78 156 0 1151193392 11407056 18809568
23:14:53 9403 8192000000 10091924960 10447953920 1172611776 12.521 100 89 178 0 1206119440 12782560 20725104
23:16:05 4309 8192000000 10129590384 10496548864 1216412992 12.928 100 81 162 0 1247268816 12648096 18207728
23:17:12 2569 8192000000 10156856576 10531516416 1245561984 13.197 100 78 152 2 1274265136 12308272 16394880
23:18:17 2023 8192000000 10183107440 10565079040 1271700752 13.437 100 86 171 1 1302755280 11704832 19349696
23:19:20 29869 8192000000 10197637472 10585899008 1290559456 13.609 100 71 142 0 1316851024 9876416 16415152
23:20:50 2155 8192000000 10199774736 10600501248 1304431120 13.736 100 43 86 2 1321720480 8439872 8849488
23:21:54 12243 8192000000 10195252224 10610348032 1312583440 13.810 100 13 26 0 1320850544 5395952 2871152
23:23:07 13460 8192000000 10212179216 10631008256 1324309712 13.916 100 22 43 0 1339246432 9827088 5109632
23:24:22 20684 8192000000 10209929024 10632871936 1327429424 13.944 100 22 44 2 1338131536 6086112 4616000
23:25:44 9063 8192000000 10227746064 10645377024 1330409296 13.971 100 27 54 1 1347690048 10926064 6354688
23:26:55 3073 8192000000 10221749088 10637791232 1327364304 13.943 100 22 44 0 1338893040 6518480 5010256
23:27:58 53692 8192000000 10198937584 10604474368 1266437984 13.389 96 28 56 2 1278139760 5166432 6535344
23:29:52 3597 8192000000 10179899456 10584084480 1276050544 13.477 99 41 82 3 1292326080 6937872 9337664
23:30:57 6800 8192000000 10127281104 10523070464 1193173872 12.713 95 20 40 1 1203020128 5651760 4194496
23:32:07 8591 8192000000 10159992576 10563276800 1266672896 13.391 100 22 44 0 1281577952 9565152 5339904
23:33:15 21925 8192000000 10189453712 10568826880 1269071504 13.413 100 83 167 4 1295035024 5555536 20407984
23:34:38 5024 8192000000 10192076592 10589724672 1287788080 13.584 100 44 89 1 1307252560 7898528 11565952
23:35:44 11113 8192000000 10199166816 10606489600 1302395008 13.717 100 25 50 1 1313599824 5534048 5670768
23:36:59 65736 8192000000 10202055248 10617905152 1310764096 13.793 100 29 59 0 1322922816 4503312 7655408
23:39:14 14271 8192000000 10269041680 10669506560 1353674880 14.181 100 65 128 3 1377951392 10429648 13846864
23:40:31 10182 8192000000 10275603920 10685509632 1359539248 14.233 100 56 113 0 1382479248 10397616 12542384
23:41:44 8025 8192000000 10270488704 10688790528 1365645760 14.288 100 49 98 0 1386468208 9286336 11536112
23:42:57 1994 8192000000 10289064880 10706567168 1377369824 14.393 100 60 119 0 1400435920 10053680 13012416
23:44:04 15827 8192000000 10303178848 10721992704 1389693648 14.503 100 54 108 0 1412331504 11177184 11460672
23:45:28 8610 8192000000 10305453920 10729205760 1392529248 14.528 100 68 136 0 1419322928 11248816 15544864
23:46:40 14206 8192000000 10299446496 10726014976 1392245136 14.526 100 59 118 2 1417596784 12117072 13234576
23:48:38 6509 8192000000 10282829040 10703659008 1371682688 14.342 99 53 105 1 1395266640 10594416 12989536
23:49:48 14784 8192000000 10282445792 10710724608 1379909200 14.416 100 47 94 0 1400784784 9858464 11017120
23:51:18 12611 8192000000 10307508048 10729979904 1389173312 14.498 100 57 114 1 1416025680 13810848 13041520
23:52:59 6357 8192000000 10299781936 10713841664 1364244784 14.275 99 52 104 1 1396642816 19221136 13176896
23:54:09 3271 8192000000 10279261008 10692861952 1357448592 14.214 100 65 131 1 1393030480 19712208 15869680
23:55:18 8169 8192000000 10247393264 10649522176 1321646944 13.892 100 67 132 1 1361519968 22497328 17375696
23:56:39 3212 8192000000 10277577552 10680020992 1340392992 14.061 100 71 142 2 1385055088 27554320 17107776
23:57:49 7380 8192000000 10289691792 10702372864 1361829568 14.254 100 59 118 1 1404233344 28040336 14363440
23:59:00 4115 8192000000 10293609936 10697555968 1344838416 14.101 99 71 142 1 1391398384 28997456 17562512
00:00:08 6069 8192000000 10301104064 10711715840 1364370768 14.277 100 54 108 0 1410297520 31536384 14390368
00:01:24 8921 8192000000 10317495232 10733682688 1376290480 14.383 100 50 99 2 1419655936 30472224 12893232
00:02:41 4065 8192000000 10334236432 10736222208 1378688416 14.405 100 81 160 2 1435902368 36956752 20257200
00:03:48 20743 8192000000 10323349344 10751913984 1385211104 14.463 100 35 70 2 1437072112 42101584 9759424
00:05:17 3181 8192000000 10349508208 10764582912 1395924912 14.559 100 64 128 2 1452760256 39911984 16923360
00:06:22 15186 8192000000 10330211648 10765414400 1389881248 14.505 100 36 72 1 1436053824 36310656 9861920
00:07:40 9261 8192000000 10334404336 10769690624 1392540720 14.529 100 41 82 0 1444611648 41319600 10751328
00:08:56 27967 8192000000 10319532224 10761474048 1361601376 14.252 97 26 52 1 1405003552 36816144 6586032
00:10:27 66153 8192000000 10303645200 10757545984 1359273488 14.231 98 19 38 1 1389196496 24885104 5037904
00:12:35 84818 8192000000 10300392608 10745995264 1364481664 14.278 98 11 23 0 1382351712 15511232 2358816
00:15:00 67968 8192000000 10317809216 10751016960 1384728768 14.459 99 45 88 2 1408898240 14652512 9516960
00:17:12 91619 8192000000 10327556960 10769801216 1399733392 14.593 100 26 51 1 1430517040 23113936 7669712
00:19:46 96694 8192000000 10303410704 10756698112 1376920528 14.389 98 13 26 0 1393622288 13670816 3030944
00:22:27 66828 8192000000 10310728832 10742362112 1383169680 14.445 99 34 68 1 1400103840 8098800 8835360
00:24:36 10363 8192000000 10322959504 10763612160 1405663888 14.645 100 57 114 1 1431099632 10221136 15214608
00:25:52 43030 8192000000 10312877824 10773897216 1385365904 14.464 98 40 80 0 1411879376 16402912 10110560
00:27:36 74780 8192000000 10313981728 10775580672 1403577120 14.627 100 12 24 0 1425498496 18892720 3028656
00:29:52 36191 8192000000 10271998192 10719903744 1335788848 14.019 96 5 10 0 1348071776 10248256 2034672
00:31:33 54683 8192000000 10275718224 10738438144 1370132656 14.328 99 24 48 0 1383441136 8042928 5265552
00:33:36 40120 8192000000 10271324976 10726404096 1352226672 14.168 97 18 36 1 1366895520 10931088 3737760
00:35:17 98307 8192000000 10265641792 10731069440 1349103392 14.139 97 12 24 0 1361451456 9533616 2814448
00:38:03 49055 8192000000 10273132192 10734612480 1364034752 14.274 99 9 18 1 1374812384 8898448 1879184
00:39:55 65542 8192000000 10262330272 10716266496 1299766224 13.693 94 17 34 0 1313322688 9281168 4275296
00:42:00 28297 8192000000 10235433760 10681282560 1317334992 13.853 98 10 20 0 1328595328 8980384 2279952
00:43:29 39357 8192000000 10256898464 10703077376 1327685856 13.946 98 23 46 1 1341492432 8771664 5034912
00:45:09 44989 8192000000 10257945808 10697629696 1299433536 13.690 95 21 42 0 1314657376 10499088 4724752
00:46:55 45653 8192000000 10267048944 10704171008 1330536240 13.972 98 23 45 0 1345350224 9497312 5316672
00:48:41 33348 8192000000 10289895728 10744561664 1374803680 14.370 100 20 40 0 1393023744 13824448 4395616
00:50:17 61180 8192000000 10265844160 10722332672 1299550464 13.691 95 10 20 0 1316804384 15006880 2247040
00:52:49 110906 8192000000 10274671232 10727256064 1363736592 14.271 99 16 32 0 1374890496 7348208 3805696
00:55:57 19161 8192000000 10259292512 10719162368 1352299040 14.168 99 21 42 0 1370118112 12973664 4845408
00:57:18 39553 8192000000 10267726528 10732707840 1375218784 14.374 100 4 8 0 1385046928 9184656 643488
00:58:58 66161 8192000000 10277748192 10739003392 1351514368 14.161 98 17 34 0 1368683712 13017008 4152336
01:01:05 76550 8192000000 10284580544 10745917440 1369123584 14.319 99 8 16 0 1387870272 16979072 1767616
01:03:23 58940 8192000000 10283236768 10749136896 1380185952 14.418 99 16 32 0 1394138240 10394064 3558224
01:05:22 44353 8192000000 10290861248 10747342848 1375718672 14.378 99 40 80 1 1394373504 9603616 9051216
01:07:07 57256 8192000000 10291486016 10751287296 1390864896 14.514 100 20 40 1 1407856768 12135312 4856560
01:09:15 114148 8192000000 10323110944 10787540992 1409430944 14.679 100 19 38 0 1428722256 15386576 3904736
01:12:23 78058 8192000000 10287339840 10748366848 1310174512 13.788 93 10 20 0 1324660848 12424768 2061568
01:14:41 27982 8192000000 10311245616 10755190784 1378721696 14.405 99 38 75 2 1396260944 9106576 8432672
01:16:10 77876 8192000000 10290750928 10747633664 1377160928 14.391 99 16 32 0 1399556512 18961392 3434192
01:18:34 79354 8192000000 10305378144 10766430208 1368708800 14.315 98 26 52 0 1394534320 19642816 6182704
01:20:55 38306 8192000000 10286251296 10760380416 1376282768 14.383 99 14 28 0 1394376512 14880272 3213472
01:22:33 40998 8192000000 10277409584 10749661184 1337611584 14.036 96 10 20 1 1353438880 13858544 1968752
01:24:26 47159 8192000000 10271178752 10737385472 1369785120 14.325 99 12 24 1 1381029344 8377184 2867040
01:26:15 24486 8192000000 10267341072 10721132544 1359178048 14.230 99 22 45 0 1370968160 7050336 4739776
01:27:44 60168 8192000000 10265750720 10733838336 1374385584 14.366 100 12 24 0 1386990736 10113376 2491776
01:29:45 69427 8192000000 10267455072 10731139072 1342227040 14.077 98 18 34 1 1353628288 7762752 3638496
01:31:55 38678 8192000000 10258868016 10708619264 1333084768 13.995 98 14 28 0 1343597680 7596048 2916864
01:33:39 37270 8192000000 10253944672 10704572416 1322793344 13.902 97 21 42 1 1341683840 14011488 4879008
01:35:17 82234 8192000000 10276916368 10743275520 1368193552 14.311 100 5 10 0 1381854624 12714560 946512
01:37:42 22808 8192000000 10296615888 10742718464 1379662512 14.414 100 33 66 0 1395718752 8757552 7298688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-04-2022 01:39:13 to 15-04-2022 01:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-04-2022 01:39:29 to 15-04-2022 01:39:31.

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          247435
MON$OLDEST_ACTIVE               248005
MON$OLDEST_SNAPSHOT             248005
MON$NEXT_TRANSACTION            248005
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                       2049120
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:433: 
:434: mscs-test-161.budzdorov.ru    Fri Apr 15 01:38:27 2022
:435:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:436: 
--- end of diff output ---

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

15.04.22 01:39:32. 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-04-15 01:39:35 No severe PSQL-related problems occured
15.04.22 01:39:36. End of report.