Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.02.2023 16: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 15151028 kB
MemAvailable 30471612 kB
Buffers 0 kB
Cached 16580584 kB
SwapCached 3080 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.11
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 2023-02-28 16:38:39
DTS_END                         2023-02-28 16:38:39.0730
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57484
TRN_ID                          243571
ATT_ID                          705
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: 9663.91 21798 1159669
customer order: creation 178.65 354 21438
customer order: refuse 14.93 310 1792
order to supplier: creation 47.51 1304 5701
order to supplier: removal 3.49 109104 419
invoice (draft): creation 47.42 3319 5690
invoice (draft): removal 56.83 854 6820
invoice accept: apply 41.91 16850 5029
invoice accept: cancel 51.34 730 6161
customer reserve: creation 3884.13 165 466095
customer reserve: removal 5032.77 58 603932
realization accept: apply 85.76 166 10291
realization accept: cancel 87.12 106 10454
payment from customer: creation 60.48 97 7258
payment from customer: removal 5.67 88 680
payment to supplier: creation 49.73 110 5968
payment to supplier: removal 3.36 75 403
service: total inventory turnovers 4.93 1284 592
service: total monetary turnovers 6.94 37 833
service: aggregate perf. data 0.91 16370 109
service: refresh index statistics 0.03 284578 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-02-2023 16:40:14 to 28-02-2023 16:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 503
TEST_TIME 2 1363
TEST_TIME 3 2960
TEST_TIME 4 2943
TEST_TIME 5 2913
TEST_TIME 6 3482
TEST_TIME 7 3758
TEST_TIME 8 3889
TEST_TIME 9 4278
TEST_TIME 10 4868
TEST_TIME 11 5140
TEST_TIME 12 5346
TEST_TIME 13 5590
TEST_TIME 14 5742
TEST_TIME 15 6439
TEST_TIME 16 6397
TEST_TIME 17 6951
TEST_TIME 18 7245
TEST_TIME 19 7842
TEST_TIME 20 8334
TEST_TIME 21 8675
TEST_TIME 22 9482
TEST_TIME 23 9648
TEST_TIME 24 9755
TEST_TIME 25 9574
TEST_TIME 26 9547
TEST_TIME 27 10709
TEST_TIME 28 11509
TEST_TIME 29 11550
TEST_TIME 30 11304
TEST_TIME 31 11223
TEST_TIME 32 11282
TEST_TIME 33 11185
TEST_TIME 34 10950
TEST_TIME 35 10918
TEST_TIME 36 10904
TEST_TIME 37 10778
TEST_TIME 38 10627
TEST_TIME 39 10499
TEST_TIME 40 10530
TEST_TIME 41 10498
TEST_TIME 42 10607
TEST_TIME 43 10604
TEST_TIME 44 10617
TEST_TIME 45 10560
TEST_TIME 46 10522
TEST_TIME 47 10433
TEST_TIME 48 10310
TEST_TIME 49 10280
TEST_TIME 50 10174
TEST_TIME 51 10121
TEST_TIME 52 10070
TEST_TIME 53 9934
TEST_TIME 54 9909
TEST_TIME 55 9960
TEST_TIME 56 9883
TEST_TIME 57 10053
TEST_TIME 58 10065
TEST_TIME 59 10013
TEST_TIME 60 10107
TEST_TIME 61 10151
TEST_TIME 62 10082
TEST_TIME 63 10243
TEST_TIME 64 10263
TEST_TIME 65 10236
TEST_TIME 66 10247
TEST_TIME 67 10252
TEST_TIME 68 10261
TEST_TIME 69 10159
TEST_TIME 70 10122
TEST_TIME 71 10052
TEST_TIME 72 10019
TEST_TIME 73 10016
TEST_TIME 74 10039
TEST_TIME 75 9970
TEST_TIME 76 9933
TEST_TIME 77 9918
TEST_TIME 78 9908
TEST_TIME 79 9952
TEST_TIME 80 9909
TEST_TIME 81 9878
TEST_TIME 82 10020
TEST_TIME 83 10062
TEST_TIME 84 10065
TEST_TIME 85 10051
TEST_TIME 86 10045
TEST_TIME 87 10064
TEST_TIME 88 10096
TEST_TIME 89 10090
TEST_TIME 90 10047
TEST_TIME 91 9951
TEST_TIME 92 9975
TEST_TIME 93 9970
TEST_TIME 94 9921
TEST_TIME 95 9887
TEST_TIME 96 9843
TEST_TIME 97 9818
TEST_TIME 98 9765
TEST_TIME 99 9734
TEST_TIME 100 9692
TEST_TIME 101 9722
TEST_TIME 102 9724
TEST_TIME 103 9780
TEST_TIME 104 9769
TEST_TIME 105 9741
TEST_TIME 106 9791
TEST_TIME 107 9872
TEST_TIME 108 9900
TEST_TIME 109 9877
TEST_TIME 110 9910
TEST_TIME 111 9982
TEST_TIME 112 9981
TEST_TIME 113 9895
TEST_TIME 114 9891
TEST_TIME 115 9851
TEST_TIME 116 9808
TEST_TIME 117 9753
TEST_TIME 118 9736
TEST_TIME 119 9710
TEST_TIME 120 9665

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-02-2023 16:40:17 to 28-02-2023 16:40:21.

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 1591663 1591663 0 0.00 0 21416 6 0 0
doc_list_biud 1591663 1591663 0 0.00 0 59635 28 0 0
sp_add_invoice_to_stock 5153 5029 124 2.40 53 175073 16850 0 124
sp_cancel_adding_invoice 6161 6161 0 0.00 4 73103 730 0 0
sp_cancel_client_order 1792 1792 0 0.00 4 9966 310 0 0
sp_cancel_customer_reserve 603932 603932 0 0.00 0 29297 59 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 3 5584 88 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 5997 75 0 0
sp_cancel_supplier_invoice 6828 6820 8 0.11 2 59677 854 0 8
sp_cancel_supplier_order 419 419 0 0.00 6 1098068 109104 0 0
sp_cancel_write_off 10454 10454 0 0.00 1 13637 106 0 0
sp_client_order 21438 21438 0 0.00 3 42889 355 0 0
sp_customer_reserve 466095 466095 0 0.00 0 140358 165 0 0
sp_fill_shopping_cart 32819 32819 0 0.00 1 15587 37 0 0
sp_fill_shopping_cart_clo_res 466093 466093 0 0.00 0 20486 14 0 0
sp_get_clo_for_invoice 5026 5026 0 0.00 0 34144 322 0 0
sp_kill_cost_storno 17698 17698 0 0.00 0 15416 58 0 0
sp_kill_qstorno_ret_qs2qd 1225557 1225557 0 0.00 0 59634 32 0 0
sp_kill_qty_storno 644895 644895 0 0.00 0 59634 69 0 0
sp_lock_selected_doc 21153 21153 0 0.00 0 8986 6 0 0
sp_make_cost_storno 28543 28543 0 0.00 0 11679 46 0 0
sp_multiply_rows_for_pdistr 28543 28543 0 0.00 0 9729 26 0 0
sp_multiply_rows_for_qdistr 21438 21438 0 0.00 0 42874 164 0 0
sp_pay_from_customer 7258 7258 0 0.00 4 16918 97 0 0
sp_pay_to_supplier 5968 5968 0 0.00 4 17572 111 0 0
sp_payment_common 13226 13226 0 0.00 1 9589 23 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 9932 279 0 0
sp_qd_handle_on_invoice_upd_sts 11187 11187 0 0.00 0 15330 318 0 0
sp_qd_handle_on_reserve_upd_sts 10291 10291 0 0.00 0 12121 64 0 0
sp_reserve_write_off 10291 10291 0 0.00 6 12154 166 0 0
sp_supplier_invoice 5690 5690 0 0.00 6 106536 3320 0 0
sp_supplier_order 5701 5701 0 0.00 5 49603 1305 0 0
srv_aggregate_perf_data 110 109 1 0.90 2481 131305 16371 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1338 146846 43850 0 0
srv_find_qd_qs_mism 1111130 1111130 0 0.00 0 14343 5 0 0
srv_make_invnt_saldo 592 592 0 0.00 46 72579 1284 0 0
srv_make_money_saldo 833 833 0 0.00 0 11638 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 171126 330470 284579 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5029 5029 0 0.00 0 19337 174 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 3325 77 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 1812 12 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 1650 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 1215 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 1061 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 3513 33 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 28284 1633 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 3025 19 0 0
t$perf-norm:sp_client_order 21438 21438 0 0.00 0 17929 14 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 7907 17 0 0
t$perf-norm:sp_pay_from_customer 7258 7258 0 0.00 0 9698 9 0 0
t$perf-norm:sp_pay_to_supplier 5968 5968 0 0.00 0 16789 11 0 0
t$perf-norm:sp_reserve_write_off 10291 10291 0 0.00 0 11234 14 0 0
t$perf-norm:sp_supplier_invoice 5690 5690 0 0.00 0 6787 16 0 0
t$perf-norm:sp_supplier_order 5701 5701 0 0.00 0 7595 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 485 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 400 17 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 707 18 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 531 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10300 10052 248 2.40 0 3543 18 0 248
v_all_customers 39502 39502 0 0.00 0 26 1 0 0
v_all_suppliers 31586 31586 0 0.00 0 33 1 0 0
v_all_wares 42876 42876 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 734 7 0 0
v_cancel_client_order 3584 3584 0 0.00 0 2084 3 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2905 10 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 1605 4 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 3272 8 0 0
v_cancel_supplier_invoice 870 854 16 1.83 0 4405 22 0 16
v_cancel_supplier_order 838 838 0 0.00 0 3270 8 0 0
v_cancel_write_off 2062 2062 0 0.00 0 2256 12 0 0
v_max_id_clo_ord 5701 5701 0 0.00 0 807 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 3786 18 0 0
v_max_id_ord_sup 5680 5680 0 0.00 0 5462 5 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 1351 4 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 4203 6 0 0
v_min_id_clo_ord 5701 5701 0 0.00 0 8339 6 0 0
v_min_id_clo_res 989 989 0 0.00 0 3679 6 0 0
v_min_id_ord_sup 5680 5680 0 0.00 0 6436 6 0 0
v_min_non_paid_invoice 5968 5968 0 0.00 0 4232 13 0 0
v_min_non_paid_realizn 7258 7258 0 0.00 0 5016 17 0 0
v_our_firm 10438 10438 0 0.00 0 21 1 0 0
v_reserve_write_off 20582 20582 0 0.00 0 2320 4 0 0
x_lock_depdocs_on_canc_invoice 12554 12554 0 0.00 0 14700 102 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 14348 393 0 0
x_make_qstorno_1000_1200 5701 5701 0 0.00 2 49577 1235 0 0
x_make_qstorno_1200_2000 5690 5690 0 0.00 3 101245 3177 0 0
x_make_qstorno_2100_3300 466095 466095 0 0.00 0 140356 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-02-2023 16:40:22 to 28-02-2023 16:40:26.

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:42 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:43 25149 8192000000 9911131008 10221707264 992991168 10.811 100 92 184 0 1023802736 6590096 24221472
14:11:14 12358 8192000000 9948708704 10270318592 1033918400 11.206 100 92 184 1 1066570880 8621632 24030848
14:12:32 24674 8192000000 9981979920 10316001280 1061350864 11.469 100 81 162 0 1091493600 10072480 20070256
14:14:04 7427 8192000000 10031474768 10374197248 1115065136 11.980 100 90 180 0 1148421136 11300176 22055824
14:15:16 5727 8192000000 10070372896 10425806848 1157821424 12.383 100 73 144 0 1185697280 11320880 16554976
14:16:30 2703 8192000000 10114843808 10482450432 1206922832 12.841 100 75 150 0 1235072240 11521696 16627712
14:17:34 2196 8192000000 10156382336 10532073472 1242368240 13.168 100 73 142 0 1271143680 13184832 15590608
14:18:39 2446 8192000000 10181191456 10566676480 1275501872 13.472 100 79 156 0 1303475216 11244912 16728432
14:19:44 4908 8192000000 10202995952 10586820608 1288648080 13.592 100 80 159 1 1318542352 12647056 17247216
14:20:51 2046 8192000000 10207492256 10597584896 1303904048 13.731 100 70 138 1 1329983856 10313408 15766400
14:21:55 7651 8192000000 10196486688 10600955904 1306980496 13.759 100 26 50 0 1319593504 7142432 5470576
14:23:03 10002 8192000000 10199856464 10617061376 1320940288 13.885 100 15 30 0 1329929936 5853888 3135760
14:24:14 6486 8192000000 10224368368 10641661952 1337764976 14.037 100 29 58 0 1351467312 7503856 6198480
14:25:22 17116 8192000000 10228205648 10654892032 1339688144 14.055 100 21 42 1 1354686896 10571616 4427136
14:26:39 6349 8192000000 10235117552 10655727616 1346618176 14.117 100 25 51 1 1357516848 5127024 5771648
14:27:47 7870 8192000000 10218133232 10633486336 1301266416 13.707 97 23 46 0 1312005808 4972528 5766864
14:28:57 58230 8192000000 10183539472 10587234304 1243866736 13.182 94 23 45 0 1253433776 4783696 4783344
14:30:56 3720 8192000000 10157355696 10558849024 1254826816 13.283 98 20 40 0 1264683104 5319216 4537072
14:32:00 9523 8192000000 10152181168 10550722560 1229134720 13.046 97 21 42 0 1238996192 4696496 5164976
14:33:12 9560 8192000000 10155667824 10551222272 1254112512 13.276 100 27 53 1 1265325504 4744064 6468928
14:34:23 28068 8192000000 10160651184 10568691712 1251424944 13.251 98 14 26 1 1259296144 4957040 2914160
14:35:51 8579 8192000000 10190726144 10599596032 1292800032 13.630 100 19 38 0 1302920096 5842688 4277376
14:37:03 10204 8192000000 10188872304 10604568576 1302619568 13.719 100 22 44 0 1313585840 5994800 4971472
14:38:15 128941 8192000000 10237460672 10647158784 1331150672 13.978 100 23 46 0 1341824144 4863584 5809888
14:41:27 19526 8192000000 10273313136 10693545984 1369483280 14.322 100 41 79 2 1392954000 14244864 9225856
14:42:53 33175 8192000000 10302901248 10722906112 1389124560 14.498 100 48 95 0 1419490256 18648528 11717168
14:44:36 11659 8192000000 10317865520 10739126272 1399300832 14.589 100 46 92 0 1433621712 22562880 11758000
14:45:55 27234 8192000000 10325152128 10750074880 1404200224 14.632 100 42 84 0 1436646016 22253456 10192336
14:47:33 8252 8192000000 10334865776 10757627904 1405273712 14.642 99 46 92 1 1445368304 28396640 11697952
14:48:45 5089 8192000000 10339084720 10759245824 1392292160 14.526 99 47 92 1 1440497360 36223568 11981632
14:49:54 3655 8192000000 10368197744 10783727616 1408731280 14.673 99 74 145 0 1463200992 37104848 17364864
14:51:05 3612 8192000000 10355952560 10768920576 1390799456 14.513 98 72 144 0 1451054272 42329040 17925776
14:52:14 3584 8192000000 10384252064 10799120384 1424289888 14.811 100 65 131 1 1488122016 46134048 17698080
14:53:21 3871 8192000000 10391588368 10813820928 1436530992 14.919 100 60 118 0 1496257056 44194208 15531856
14:54:29 4807 8192000000 10382902256 10798727168 1419268976 14.766 100 73 145 1 1486064464 48443712 18351776
14:55:40 4501 8192000000 10395693568 10816507904 1417489232 14.750 99 65 130 0 1492041904 57354224 17198448
14:56:51 1452 8192000000 10360812064 10763632640 1384498112 14.457 100 86 172 0 1461224384 54479248 22247024
14:57:56 5085 8192000000 10316249568 10736787456 1356470128 14.206 100 52 101 2 1423286768 53171936 13644704
14:59:09 4199 8192000000 10335593680 10764058624 1375683984 14.378 100 45 90 1 1440784688 53253248 11847456
15:00:17 1335 8192000000 10369662416 10784997376 1400499248 14.599 100 69 133 2 1465727776 48009712 17218816
15:01:19 19761 8192000000 10331703328 10765611008 1400625136 14.601 100 24 48 0 1441327568 34430032 6272400
15:02:45 33318 8192000000 10356424272 10797219840 1409010336 14.675 100 28 56 0 1459113248 42623904 7479008
15:04:26 29774 8192000000 10337961936 10780393472 1374481216 14.367 100 23 46 0 1442380208 60307312 7591680
15:06:16 14127 8192000000 10290323872 10738257920 1336758832 14.028 98 44 90 0 1371832784 24479968 10593984
15:07:31 86068 8192000000 10264860464 10699251712 1310635824 13.792 96 24 48 0 1333959168 17524992 5798352
15:09:58 104280 8192000000 10277893120 10742452224 1373777952 14.361 100 27 55 0 1393048464 12943344 6327168
15:13:27 69112 8192000000 10285101200 10736173056 1371742560 14.343 100 18 35 0 1384495488 9349504 3403424
15:15:37 112280 8192000000 10320684336 10758254592 1366639424 14.297 98 26 51 0 1383570096 11145616 5785056
15:18:36 36581 8192000000 10309080192 10765488128 1393978016 14.541 100 32 61 0 1416149120 14271232 7899872
15:20:14 26727 8192000000 10319191600 10766569472 1399142176 14.587 100 44 85 0 1430981584 21734304 10105104
15:21:42 37190 8192000000 10295329392 10763259904 1395490592 14.555 100 13 26 0 1411911664 13266256 3154816
15:24:48 40905 8192000000 10295785120 10762743808 1403543104 14.627 100 11 21 0 1412902304 7085232 2273968
15:27:09 51164 8192000000 10294849776 10767593472 1394722608 14.548 99 15 28 0 1404520800 6753648 3044544
15:29:05 15522 8192000000 10308061280 10775367680 1416886752 14.745 100 21 42 0 1429593968 8170800 4536416
15:30:48 41624 8192000000 10302161664 10764312576 1387575696 14.484 98 17 34 0 1401764432 9848224 4340512
15:32:52 45245 8192000000 10283730176 10755293184 1376885808 14.389 98 13 26 0 1390956208 10587472 3482928
15:34:39 51086 8192000000 10287804784 10758639616 1376214768 14.383 98 20 40 0 1391900976 10756352 4929856
15:36:31 34735 8192000000 10300061696 10748665856 1366319424 14.294 98 33 65 0 1379839360 6540976 6978960
15:38:07 14753 8192000000 10295768784 10739994624 1369208112 14.320 99 52 102 0 1393241184 13008960 11024112
15:39:26 75230 8192000000 10288765536 10740150272 1333220784 13.996 96 9 18 0 1349039344 14100288 1718272
15:41:42 33347 8192000000 10270577680 10726264832 1336876704 14.029 97 16 31 2 1353863840 13551232 3435904
15:43:19 46279 8192000000 10272089520 10705829888 1297522976 13.673 95 29 58 0 1312879440 9187568 6168896
15:45:06 19014 8192000000 10261157104 10693271552 1303356656 13.726 96 30 60 0 1322701808 13110976 6234176
15:46:30 73047 8192000000 10272964800 10704642048 1285805728 13.566 94 23 46 0 1300863696 9610768 5447200
15:50:12 10885 8192000000 10271390864 10715246592 1355477568 14.197 99 25 50 0 1372735056 11571808 5685680
15:51:24 48659 8192000000 10244292144 10705219584 1305859536 13.748 96 12 24 0 1322893616 14513280 2520800
15:53:14 45623 8192000000 10252763392 10693005312 1318674432 13.865 98 17 32 0 1329645024 7163984 3806608
15:55:35 52437 8192000000 10256763664 10705952768 1328337888 13.952 98 11 22 0 1342592176 11650560 2603728
15:57:41 51338 8192000000 10282752784 10726572032 1356631808 14.207 99 22 44 0 1379953120 18176672 5144640
15:59:32 37880 8192000000 10285768000 10734006272 1367262064 14.303 99 21 42 2 1392831456 20284896 5284496
16:01:13 92836 8192000000 10274367632 10719481856 1313499328 13.818 96 18 36 0 1326533232 8926352 4107552
16:03:50 60747 8192000000 10282380448 10725695488 1357412432 14.214 99 35 70 0 1375050272 10316336 7321504
16:05:53 89445 8192000000 10290486064 10746449920 1349886864 14.146 98 13 25 0 1366460528 13756304 2817360
16:08:30 146846 8192000000 10283652480 10746949632 1369821280 14.325 99 20 40 0 1387365024 13027552 4516192
16:11:58 45845 8192000000 10294197984 10754969600 1396065952 14.560 100 10 20 0 1407700880 9843840 1791088
16:14:20 52236 8192000000 10278093648 10739896320 1378053456 14.399 99 11 22 0 1387321328 6803232 2464640
16:16:19 95561 8192000000 10294250880 10760744960 1377286320 14.392 99 22 44 0 1391253504 8813968 5153216
16:19:23 66723 8192000000 10282128592 10738294784 1338434544 14.043 96 16 32 0 1354778048 12408176 3935328
16:21:32 41776 8192000000 10297911008 10755137536 1391166912 14.516 100 19 38 0 1407524880 11825984 4531984
16:23:17 30783 8192000000 10290385792 10740310016 1374210768 14.365 100 19 38 0 1393614000 14845536 4557696
16:24:49 81151 8192000000 10267177312 10706046976 1322026496 13.895 96 26 52 1 1349078224 21047200 6004528
16:27:12 32038 8192000000 10296829184 10739671040 1376403488 14.384 100 28 56 0 1391339024 8459632 6475904
16:28:55 107275 8192000000 10281731984 10749235200 1326942128 13.940 96 9 18 0 1344889360 16003712 1943520
16:31:42 66252 8192000000 10255585120 10717949952 1328738768 13.956 97 30 58 0 1348291200 13082864 6469568
16:33:55 67057 8192000000 10251335792 10715414528 1340104464 14.058 99 11 23 0 1358834976 16186640 2543872
16:36:02 57223 8192000000 10229459472 10688552960 1316725072 13.847 98 6 12 0 1331069472 13217600 1126800
16:38:02 44585 8192000000 9167115920 9428254720 328859360 3.859 25 4 8 0 332056992 2598768 598864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-02-2023 16:40:28 to 28-02-2023 16:40:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 124 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 28-02-2023 16:40:44 to 28-02-2023 16:40:46.

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          243880
MON$OLDEST_ACTIVE               243881
MON$OLDEST_SNAPSHOT             243881
MON$NEXT_TRANSACTION            243881
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                       2048032
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:754: 
:755: mscs-test-161.budzdorov.ru    Tue Feb 28 16:38:56 2023
:756:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:757: 
--- end of diff output ---

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

28.02.23 16:40:47. 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
2023-02-28 16:40:50 No severe PSQL-related problems occured
28.02.23 16:40:51. End of report.