Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 22: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 16928560 kB
MemAvailable 31569904 kB
Buffers 0 kB
Cached 14772208 kB
SwapCached 2204 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-01 22:38:10
DTS_END                         2022-04-01 22:38:10.9430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40534
TRN_ID                          238156
ATT_ID                          682
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: 9070.60 25873 1088472
customer order: creation 168.20 382 20184
customer order: refuse 15.32 337 1838
order to supplier: creation 45.48 1241 5457
order to supplier: removal 3.31 114287 397
invoice (draft): creation 46.23 3523 5547
invoice (draft): removal 50.85 904 6102
invoice accept: apply 40.64 18138 4877
invoice accept: cancel 46.53 885 5584
customer reserve: creation 3769.58 179 452350
customer reserve: removal 4593.13 64 551176
realization accept: apply 82.23 161 9868
realization accept: cancel 81.51 115 9781
payment from customer: creation 57.62 84 6914
payment from customer: removal 5.53 63 664
payment to supplier: creation 48.37 106 5804
payment to supplier: removal 3.37 59 404
service: total inventory turnovers 5.03 1138 603
service: total monetary turnovers 6.78 22 813
service: aggregate perf. data 0.88 16893 105
service: refresh index statistics 0.03 358887 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2816
TEST_TIME 2 2916
TEST_TIME 3 3323
TEST_TIME 4 4330
TEST_TIME 5 5068
TEST_TIME 6 5045
TEST_TIME 7 5107
TEST_TIME 8 5124
TEST_TIME 9 5151
TEST_TIME 10 5347
TEST_TIME 11 5520
TEST_TIME 12 5946
TEST_TIME 13 6003
TEST_TIME 14 6259
TEST_TIME 15 6296
TEST_TIME 16 6276
TEST_TIME 17 6744
TEST_TIME 18 6658
TEST_TIME 19 6648
TEST_TIME 20 7476
TEST_TIME 21 8382
TEST_TIME 22 8320
TEST_TIME 23 8750
TEST_TIME 24 8868
TEST_TIME 25 9028
TEST_TIME 26 9585
TEST_TIME 27 11022
TEST_TIME 28 10716
TEST_TIME 29 10541
TEST_TIME 30 10618
TEST_TIME 31 10381
TEST_TIME 32 10127
TEST_TIME 33 9989
TEST_TIME 34 10054
TEST_TIME 35 9790
TEST_TIME 36 9731
TEST_TIME 37 9685
TEST_TIME 38 9508
TEST_TIME 39 9417
TEST_TIME 40 9355
TEST_TIME 41 9486
TEST_TIME 42 9520
TEST_TIME 43 9499
TEST_TIME 44 9411
TEST_TIME 45 9475
TEST_TIME 46 9590
TEST_TIME 47 9512
TEST_TIME 48 9443
TEST_TIME 49 9460
TEST_TIME 50 9509
TEST_TIME 51 9400
TEST_TIME 52 9507
TEST_TIME 53 9493
TEST_TIME 54 9511
TEST_TIME 55 9533
TEST_TIME 56 9547
TEST_TIME 57 9389
TEST_TIME 58 9412
TEST_TIME 59 9411
TEST_TIME 60 9321
TEST_TIME 61 9280
TEST_TIME 62 9276
TEST_TIME 63 9263
TEST_TIME 64 9159
TEST_TIME 65 9145
TEST_TIME 66 9220
TEST_TIME 67 9238
TEST_TIME 68 9264
TEST_TIME 69 9227
TEST_TIME 70 9187
TEST_TIME 71 9281
TEST_TIME 72 9305
TEST_TIME 73 9341
TEST_TIME 74 9350
TEST_TIME 75 9295
TEST_TIME 76 9340
TEST_TIME 77 9348
TEST_TIME 78 9391
TEST_TIME 79 9378
TEST_TIME 80 9369
TEST_TIME 81 9339
TEST_TIME 82 9259
TEST_TIME 83 9315
TEST_TIME 84 9243
TEST_TIME 85 9267
TEST_TIME 86 9259
TEST_TIME 87 9201
TEST_TIME 88 9160
TEST_TIME 89 9121
TEST_TIME 90 9082
TEST_TIME 91 9091
TEST_TIME 92 9096
TEST_TIME 93 9078
TEST_TIME 94 9121
TEST_TIME 95 9182
TEST_TIME 96 9206
TEST_TIME 97 9178
TEST_TIME 98 9175
TEST_TIME 99 9176
TEST_TIME 100 9223
TEST_TIME 101 9352
TEST_TIME 102 9355
TEST_TIME 103 9327
TEST_TIME 104 9325
TEST_TIME 105 9315
TEST_TIME 106 9251
TEST_TIME 107 9233
TEST_TIME 108 9171
TEST_TIME 109 9195
TEST_TIME 110 9185
TEST_TIME 111 9152
TEST_TIME 112 9121
TEST_TIME 113 9085
TEST_TIME 114 9053
TEST_TIME 115 9090
TEST_TIME 116 9060
TEST_TIME 117 9130
TEST_TIME 118 9106
TEST_TIME 119 9141
TEST_TIME 120 9073

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-04-2022 22:39:17 to 01-04-2022 22:39:20.

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 1507856 1507856 0 0.00 0 29041 6 0 0
doc_list_biud 1507855 1507855 0 0.00 0 39265 30 0 0
sp_add_invoice_to_stock 4931 4877 54 1.09 22 208517 18139 0 54
sp_cancel_adding_invoice 5584 5584 0 0.00 4 83585 885 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 11269 337 0 0
sp_cancel_customer_reserve 551176 551176 0 0.00 1 25445 65 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 2920 63 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 1961 59 0 0
sp_cancel_supplier_invoice 6109 6102 7 0.11 1 22785 904 0 7
sp_cancel_supplier_order 397 397 0 0.00 9 1026031 114287 0 0
sp_cancel_write_off 9781 9781 0 0.00 1 16739 116 0 0
sp_client_order 20184 20184 0 0.00 4 25702 382 0 0
sp_customer_reserve 452350 452350 0 0.00 0 41821 179 0 0
sp_fill_shopping_cart 31179 31179 0 0.00 1 22448 41 0 0
sp_fill_shopping_cart_clo_res 452347 452347 0 0.00 0 21243 13 0 0
sp_get_clo_for_invoice 4873 4873 0 0.00 0 39736 385 0 0
sp_kill_cost_storno 16433 16433 0 0.00 0 8761 59 0 0
sp_kill_qstorno_ret_qs2qd 1118632 1118632 0 0.00 0 25426 35 0 0
sp_kill_qty_storno 589619 589619 0 0.00 0 39261 76 0 0
sp_lock_selected_doc 20536 20536 0 0.00 0 7683 5 0 0
sp_make_cost_storno 27459 27459 0 0.00 0 14959 43 0 0
sp_multiply_rows_for_pdistr 27459 27459 0 0.00 0 11179 26 0 0
sp_multiply_rows_for_qdistr 20184 20184 0 0.00 0 21124 184 0 0
sp_pay_from_customer 6914 6914 0 0.00 4 10323 85 0 0
sp_pay_to_supplier 5804 5804 0 0.00 5 11548 106 0 0
sp_payment_common 12718 12718 0 0.00 2 10315 22 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 11260 318 0 0
sp_qd_handle_on_invoice_upd_sts 10457 10457 0 0.00 0 39259 334 0 0
sp_qd_handle_on_reserve_upd_sts 9868 9868 0 0.00 0 13717 60 0 0
sp_reserve_write_off 9868 9868 0 0.00 6 17838 162 0 0
sp_supplier_invoice 5547 5547 0 0.00 6 103173 3524 0 0
sp_supplier_order 5457 5457 0 0.00 5 27039 1242 0 0
srv_aggregate_perf_data 106 105 1 0.94 2462 121511 16893 0 1
srv_fill_mon_cache_memory 59 59 0 0.00 2155 123667 50031 0 0
srv_find_qd_qs_mism 1041849 1041849 0 0.00 0 28943 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 37 94461 1139 0 0
srv_make_money_saldo 813 813 0 0.00 1 2019 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 196299 528756 358887 0 0
t$perf-abend:sp_add_invoice_to_stock 54 54 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 18085 187 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 9026 103 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 3355 17 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 5312 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 1952 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 4793 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 729 12 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 26282 1502 0 0
t$perf-norm:sp_cancel_write_off 954 954 0 0.00 0 4314 8 0 0
t$perf-norm:sp_client_order 20184 20184 0 0.00 0 10114 18 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 869 4 0 0
t$perf-norm:sp_pay_from_customer 6914 6914 0 0.00 0 5971 8 0 0
t$perf-norm:sp_pay_to_supplier 5804 5804 0 0.00 0 9570 16 0 0
t$perf-norm:sp_reserve_write_off 9868 9868 0 0.00 0 11439 14 0 0
t$perf-norm:sp_supplier_invoice 5547 5547 0 0.00 0 4349 13 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 4752 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 553 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 472 10 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 1365 19 0 0
t$perf-norm:srv_make_money_saldo 813 813 0 0.00 0 1291 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9854 9746 108 1.09 0 7048 14 0 108
v_all_customers 36732 36732 0 0.00 0 170 0 0 0
v_all_suppliers 30434 30434 0 0.00 0 21 1 0 0
v_all_wares 40368 40368 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1123 8 0 0
v_cancel_client_order 3676 3676 0 0.00 0 2103 4 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 2031 4 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 2174 7 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1939 11 0 0
v_cancel_supplier_invoice 748 734 14 1.87 0 2469 14 0 14
v_cancel_supplier_order 794 794 0 0.00 0 712 4 0 0
v_cancel_write_off 1908 1908 0 0.00 0 638 10 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 448 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 9580 27 0 0
v_max_id_ord_sup 5537 5537 0 0.00 0 4084 4 0 0
v_max_non_paid_invoice 1580 1580 0 0.00 0 1299 3 0 0
v_max_non_paid_realizn 3709 3709 0 0.00 0 8121 5 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 2442 4 0 0
v_min_id_clo_res 958 958 0 0.00 0 7726 13 0 0
v_min_id_ord_sup 5537 5537 0 0.00 0 9748 10 0 0
v_min_non_paid_invoice 5804 5804 0 0.00 0 9275 15 0 0
v_min_non_paid_realizn 6914 6914 0 0.00 0 10311 17 0 0
v_our_firm 10046 10046 0 0.00 0 13 1 0 0
v_reserve_write_off 19736 19736 0 0.00 0 2303 5 0 0
x_lock_depdocs_on_canc_invoice 11319 11319 0 0.00 0 16989 127 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 10286 386 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 1 26833 1171 0 0
x_make_qstorno_1200_2000 5547 5547 0 0.00 3 99158 3365 0 0
x_make_qstorno_2100_3300 452350 452350 0 0.00 0 41821 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-04-2022 22:39:21 to 01-04-2022 22:39:25.

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
20:08:14 10 8192000000 8801921248 8814301184 7670864 0.093 3 1 2 0 7771824 58176 42784
20:09:15 26493 8192000000 9900033008 10209140736 980907536 10.693 100 81 161 1 1007584368 5530736 21146096
20:10:44 31891 8192000000 9962234320 10286407680 1042169008 11.286 100 90 180 0 1073011728 7986128 22856592
20:12:22 16168 8192000000 9999232400 10336600064 1081866304 11.665 100 83 166 0 1111834416 9854544 20113568
20:13:41 15865 8192000000 10053954928 10402975744 1134148832 12.160 100 78 157 0 1163867264 11373328 18345104
20:15:03 7413 8192000000 10090075712 10448785408 1172035232 12.516 100 78 156 0 1202409392 12573504 17800656
20:16:13 6697 8192000000 10121523264 10491600896 1218398048 12.947 100 80 156 0 1248231008 12307936 17525024
20:17:24 3616 8192000000 10159569696 10534576128 1244671456 13.189 100 82 164 0 1275831456 13320768 17839232
20:18:29 2336 8192000000 10188619776 10567356416 1272580320 13.445 100 93 184 1 1305998352 12821280 20596752
20:19:34 3608 8192000000 10208781408 10591117312 1288828128 13.594 100 92 182 2 1322496768 12907184 20761456
20:20:39 19399 8192000000 10207289184 10599239680 1296689280 13.665 100 53 106 0 1317659776 9908224 11062272
20:22:00 5403 8192000000 10207023136 10609790976 1311526960 13.800 100 47 91 4 1329003920 6865728 10611232
20:23:05 11540 8192000000 10212892544 10621808640 1319073552 13.868 100 26 52 1 1330823408 5760208 5989648
20:24:20 7055 8192000000 10213324256 10629611520 1327465312 13.944 100 20 41 1 1338306160 5736080 5104768
20:25:31 65381 8192000000 10247279904 10651725824 1335421232 14.016 100 55 113 2 1354086512 5311536 13353744
20:27:36 4564 8192000000 10224625760 10641596416 1324945808 13.921 99 35 70 1 1340026352 7396624 7683920
20:28:41 11983 8192000000 10189011216 10592636928 1259249408 13.323 96 19 37 0 1269261840 5567408 4445024
20:29:53 5431 8192000000 10178639504 10585948160 1255252416 13.286 97 24 47 2 1270134256 9472608 5409232
20:31:00 7502 8192000000 10167598368 10572451840 1269008752 13.413 99 26 52 0 1280582560 5571328 6002480
20:32:11 13428 8192000000 10146850864 10545229824 1210703568 12.876 96 17 34 1 1220250880 6050208 3497104
20:33:27 50141 8192000000 10140432880 10546561024 1230982944 13.063 97 21 42 1 1241352592 5547904 4821744
20:35:17 3583 8192000000 10174487392 10586480640 1286617296 13.573 100 26 52 0 1299442304 6835616 5989392
20:36:21 8946 8192000000 10182845312 10598817792 1296406192 13.663 100 26 51 2 1309182080 6109968 6665920
20:37:30 6956 8192000000 10200305552 10611277824 1304780944 13.739 100 21 42 0 1315254672 5781424 4692304
20:38:41 100792 8192000000 10241359536 10657304576 1329132608 13.959 100 34 68 0 1342598832 5685456 7780768
20:41:32 37037 8192000000 10280219728 10700783616 1366378016 14.295 100 61 122 0 1393280384 13109616 13792752
20:44:14 30016 8192000000 10314947984 10737311744 1390762688 14.513 100 53 106 2 1424349488 21240432 12346368
20:45:56 10662 8192000000 10324992640 10747346944 1399946064 14.595 100 55 107 2 1439551056 26122480 13482512
20:47:20 3093 8192000000 10340459536 10756001792 1402585152 14.618 100 62 122 0 1449542848 32218368 14739328
20:48:36 9849 8192000000 10351307120 10770161664 1409736864 14.682 100 72 144 2 1464189728 37585920 16866944
20:50:05 10880 8192000000 10363718416 10785726464 1413783488 14.718 100 65 130 0 1475207072 45326048 16097536
20:51:24 9325 8192000000 10383802672 10813677568 1436364944 14.918 100 55 110 0 1495879776 45354336 14160496
20:52:37 5129 8192000000 10378177072 10791895040 1414700944 14.726 99 69 137 1 1481140544 49616160 16823440
20:53:56 3368 8192000000 10383937008 10801545216 1419336384 14.767 99 68 134 1 1488615216 51201440 18077392
20:55:10 2151 8192000000 10391234048 10804928512 1408021440 14.666 99 82 163 1 1488663968 60016672 20625856
20:56:18 3162 8192000000 10384725184 10791006208 1410263776 14.686 100 86 172 2 1488929456 57461712 21203968
20:57:28 3658 8192000000 10393482016 10799632384 1403005408 14.622 100 84 168 2 1493836720 68427472 22403840
20:58:36 13386 8192000000 10374887552 10797453312 1390448976 14.510 99 52 104 2 1471685792 66988144 14248672
20:59:56 17790 8192000000 10333533312 10766770176 1379264128 14.410 99 33 66 0 1438828784 50195744 9368912
21:01:19 13500 8192000000 10345448496 10769285120 1375902576 14.380 99 41 82 1 1439335712 51987728 11445408
21:02:42 16501 8192000000 10327909120 10757074944 1368753648 14.316 100 29 58 2 1430362080 53028208 8580224
21:04:07 22767 8192000000 10311752656 10750029824 1361650384 14.252 100 23 46 0 1418756816 50570240 6536192
21:06:17 28739 8192000000 10288089584 10706812928 1344083328 14.094 99 33 66 0 1364159552 12471664 7604560
21:07:48 46913 8192000000 10270490704 10715746304 1357663888 14.216 99 34 69 1 1379227952 13828320 7735744
21:09:36 108773 8192000000 10281605696 10735841280 1367266832 14.303 100 13 26 0 1377850960 7573744 3010384
21:13:05 91152 8192000000 10307116768 10749587456 1386251888 14.472 100 34 68 1 1407077856 13184640 7641328
21:15:40 95314 8192000000 10309499408 10756489216 1378592752 14.404 99 24 47 1 1393892832 9311456 5988624
21:18:16 57285 8192000000 10303826912 10766131200 1384603168 14.458 99 28 56 0 1408314112 15902320 7808624
21:20:31 53358 8192000000 10293815968 10763620352 1391330032 14.518 100 16 32 1 1407621456 12245312 4046112
21:22:27 77423 8192000000 10289313344 10754940928 1366609408 14.297 98 12 24 2 1387546864 17913680 3023776
21:24:45 75702 8192000000 10288729248 10740056064 1371798176 14.343 99 18 36 1 1386737840 10644160 4295504
21:27:01 75531 8192000000 10294654016 10748928000 1374193872 14.365 98 43 86 1 1395586704 11979552 9413280
21:29:20 25029 8192000000 10319343168 10762010624 1382419072 14.438 99 34 66 1 1402064704 11823568 7822064
21:31:36 58451 8192000000 10305767584 10756333568 1382487488 14.439 99 27 55 0 1398535776 9634976 6413312
21:33:38 52956 8192000000 10321348592 10781618176 1375039488 14.372 98 22 44 1 1395172080 15125680 5006912
21:35:50 106501 8192000000 10311413328 10762825728 1391860784 14.522 99 35 70 0 1413354672 13833024 7660864
21:38:44 4159 8192000000 10312113040 10755276800 1394085392 14.542 99 43 86 1 1412995248 9176288 9733568
21:39:49 56588 8192000000 10301859968 10762731520 1391880816 14.523 99 22 43 0 1407171376 10782256 4508304
21:41:46 46555 8192000000 10299613024 10766082048 1385271120 14.464 98 10 20 1 1395240352 7770112 2199120
21:43:56 53976 8192000000 10281091760 10743189504 1363358960 14.268 98 12 24 0 1376744256 10558288 2827008
21:46:11 74768 8192000000 10279002256 10717765632 1272572656 13.445 92 23 46 1 1285836352 7915664 5348032
21:48:32 51317 8192000000 10273574128 10726178816 1338565088 14.044 98 48 93 1 1361428992 11969616 10894288
21:50:57 123667 8192000000 10251599920 10707230720 1260399232 13.334 93 9 18 0 1274318976 12146992 1772752
21:54:01 70960 8192000000 10251041120 10702557184 1305209136 13.743 97 22 44 0 1319531584 9802832 4519616
21:56:12 105111 8192000000 10257854720 10707210240 1304694880 13.738 96 7 14 1 1320294784 14279760 1320144
21:58:58 95144 8192000000 10267820464 10714832896 1354550208 14.188 99 21 42 0 1371791696 12055376 5186112
22:02:08 49601 8192000000 10264393136 10726191104 1363899536 14.272 100 5 10 0 1377995216 13115632 980048
22:03:59 69630 8192000000 10250142368 10705604608 1343771392 14.091 99 10 20 0 1354100992 8277232 2052368
22:06:12 74683 8192000000 10272070944 10726641664 1372936496 14.353 100 22 44 1 1386599680 8662336 5000848
22:08:32 44023 8192000000 10273779440 10732822528 1378192016 14.400 100 14 28 0 1390502240 9174320 3135904
22:10:23 52204 8192000000 10288516656 10745520128 1375490560 14.376 99 9 18 1 1387146864 9585344 2070960
22:12:17 107113 8192000000 10290654896 10754801664 1361587120 14.252 98 11 22 0 1378656880 14824000 2245760
22:15:04 27698 8192000000 10303716512 10753388544 1394092416 14.542 100 31 62 0 1409240672 8545648 6602608
22:16:32 93983 8192000000 10311444608 10767142912 1360114864 14.238 97 13 26 0 1383296816 20089952 3092000
22:19:56 73499 8192000000 10304762640 10758930432 1376334912 14.384 99 13 26 1 1395217952 15412192 3470848
22:22:09 31004 8192000000 10317130368 10761478144 1386465744 14.474 99 46 91 1 1407557008 11407904 9683360
22:23:47 63702 8192000000 10294902208 10746032128 1389240192 14.499 100 13 26 0 1401185472 8963104 2982176
22:26:13 62149 8192000000 10282219040 10729132032 1349620304 14.144 97 37 74 1 1368154928 10207008 8327616
22:28:16 47069 8192000000 10235521312 10696757248 1294300384 13.643 95 3 6 0 1302321872 7474544 546944
22:30:07 45327 8192000000 10264968768 10712412160 1332965888 13.994 98 10 20 0 1341491712 6425904 2099920
22:31:53 74843 8192000000 10269826544 10726662144 1340411472 14.061 98 15 30 0 1363764736 19488432 3864832
22:35:49 48968 8192000000 10256925456 10695188480 1303966080 13.731 97 14 27 0 1314846432 7989184 2891168
22:37:38 37846 8192000000 9254587472 9529294848 400025968 4.655 30 5 10 0 404937904 3910096 1001840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 01-04-2022 22:39:27 to 01-04-2022 22:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 54 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 22:39:42 to 01-04-2022 22:39:44.

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          238433
MON$OLDEST_ACTIVE               238434
MON$OLDEST_SNAPSHOT             238434
MON$NEXT_TRANSACTION            238434
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                       2002240
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:844: 
:845: mscs-test-161.budzdorov.ru    Fri Apr  1 22:38:17 2022
:846:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:847: 
:848: 
:849: mscs-test-161.budzdorov.ru    Fri Apr  1 22:38:23 2022
:850:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:851: 
--- end of diff output ---

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

01.04.22 22:39:45. 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-01 22:39:48 No severe PSQL-related problems occured
01.04.22 22:39:49. End of report.