Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.06.2022 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 16225880 kB
MemAvailable 31276480 kB
Buffers 0 kB
Cached 15450064 kB
SwapCached 2452 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-06-08 16:39:01
DTS_END                         2022-06-08 16:38:24.6500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42930
TRN_ID                          239871
ATT_ID                          274
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: 9204.15 25107 1104498
customer order: creation 173.37 367 20804
customer order: refuse 15.43 362 1852
order to supplier: creation 45.43 1449 5452
order to supplier: removal 3.57 98978 428
invoice (draft): creation 46.42 3578 5570
invoice (draft): removal 52.31 871 6277
invoice accept: apply 41.70 17660 5004
invoice accept: cancel 47.62 791 5714
customer reserve: creation 3823.70 175 458844
customer reserve: removal 4665.34 59 559841
realization accept: apply 81.62 198 9794
realization accept: cancel 78.66 116 9439
payment from customer: creation 58.73 123 7048
payment from customer: removal 5.47 65 656
payment to supplier: creation 49.04 151 5885
payment to supplier: removal 3.27 66 392
service: total inventory turnovers 4.78 1231 574
service: total monetary turnovers 6.86 39 823
service: aggregate perf. data 0.81 21942 97
service: refresh index statistics 0.03 353922 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-06-2022 16:39:30 to 08-06-2022 16:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2820
TEST_TIME 2 3365
TEST_TIME 3 4343
TEST_TIME 4 5107
TEST_TIME 5 5419
TEST_TIME 6 5356
TEST_TIME 7 5597
TEST_TIME 8 5820
TEST_TIME 9 6000
TEST_TIME 10 6206
TEST_TIME 11 6458
TEST_TIME 12 7280
TEST_TIME 13 7358
TEST_TIME 14 7406
TEST_TIME 15 7800
TEST_TIME 16 8001
TEST_TIME 17 8228
TEST_TIME 18 9028
TEST_TIME 19 8716
TEST_TIME 20 8736
TEST_TIME 21 8931
TEST_TIME 22 9082
TEST_TIME 23 9253
TEST_TIME 24 9693
TEST_TIME 25 9812
TEST_TIME 26 10037
TEST_TIME 27 10379
TEST_TIME 28 10699
TEST_TIME 29 11280
TEST_TIME 30 11159
TEST_TIME 31 11348
TEST_TIME 32 11288
TEST_TIME 33 11031
TEST_TIME 34 11007
TEST_TIME 35 10866
TEST_TIME 36 10722
TEST_TIME 37 10570
TEST_TIME 38 10402
TEST_TIME 39 10258
TEST_TIME 40 10166
TEST_TIME 41 10097
TEST_TIME 42 10067
TEST_TIME 43 9986
TEST_TIME 44 9896
TEST_TIME 45 9859
TEST_TIME 46 9738
TEST_TIME 47 9673
TEST_TIME 48 9581
TEST_TIME 49 9507
TEST_TIME 50 9485
TEST_TIME 51 9396
TEST_TIME 52 9341
TEST_TIME 53 9321
TEST_TIME 54 9267
TEST_TIME 55 9236
TEST_TIME 56 9209
TEST_TIME 57 9233
TEST_TIME 58 9184
TEST_TIME 59 9185
TEST_TIME 60 9223
TEST_TIME 61 9197
TEST_TIME 62 9239
TEST_TIME 63 9233
TEST_TIME 64 9222
TEST_TIME 65 9324
TEST_TIME 66 9272
TEST_TIME 67 9328
TEST_TIME 68 9424
TEST_TIME 69 9357
TEST_TIME 70 9408
TEST_TIME 71 9415
TEST_TIME 72 9530
TEST_TIME 73 9459
TEST_TIME 74 9394
TEST_TIME 75 9312
TEST_TIME 76 9371
TEST_TIME 77 9304
TEST_TIME 78 9356
TEST_TIME 79 9291
TEST_TIME 80 9267
TEST_TIME 81 9292
TEST_TIME 82 9197
TEST_TIME 83 9244
TEST_TIME 84 9229
TEST_TIME 85 9265
TEST_TIME 86 9254
TEST_TIME 87 9244
TEST_TIME 88 9235
TEST_TIME 89 9253
TEST_TIME 90 9276
TEST_TIME 91 9265
TEST_TIME 92 9359
TEST_TIME 93 9360
TEST_TIME 94 9374
TEST_TIME 95 9427
TEST_TIME 96 9380
TEST_TIME 97 9426
TEST_TIME 98 9422
TEST_TIME 99 9415
TEST_TIME 100 9360
TEST_TIME 101 9328
TEST_TIME 102 9271
TEST_TIME 103 9289
TEST_TIME 104 9297
TEST_TIME 105 9250
TEST_TIME 106 9233
TEST_TIME 107 9196
TEST_TIME 108 9138
TEST_TIME 109 9155
TEST_TIME 110 9166
TEST_TIME 111 9166
TEST_TIME 112 9154
TEST_TIME 113 9198
TEST_TIME 114 9157
TEST_TIME 115 9266
TEST_TIME 116 9254
TEST_TIME 117 9237
TEST_TIME 118 9262
TEST_TIME 119 9249
TEST_TIME 120 9207

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-06-2022 16:39:33 to 08-06-2022 16:39:37.

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 1529722 1529722 0 0.00 0 25384 6 0 0
doc_list_biud 1529720 1529720 0 0.00 0 26383 28 0 0
sp_add_invoice_to_stock 5116 5004 112 2.18 40 197286 17660 0 112
sp_cancel_adding_invoice 5714 5714 0 0.00 4 55483 792 0 0
sp_cancel_client_order 1852 1852 0 0.00 4 8240 362 0 0
sp_cancel_customer_reserve 559841 559841 0 0.00 1 26406 59 0 0
sp_cancel_pay_from_customer 656 656 0 0.00 3 3900 65 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 2360 66 0 0
sp_cancel_supplier_invoice 6281 6277 4 0.06 1 26243 872 0 4
sp_cancel_supplier_order 428 428 0 0.00 8 1043543 98978 0 0
sp_cancel_write_off 9439 9439 0 0.00 1 19410 117 0 0
sp_client_order 20804 20804 0 0.00 3 26172 368 0 0
sp_customer_reserve 458844 458844 0 0.00 0 70711 176 0 0
sp_fill_shopping_cart 31821 31821 0 0.00 1 36843 45 0 0
sp_fill_shopping_cart_clo_res 458841 458841 0 0.00 0 16086 13 0 0
sp_get_clo_for_invoice 5000 5000 0 0.00 0 25314 272 0 0
sp_kill_cost_storno 16201 16201 0 0.00 0 9930 57 0 0
sp_kill_qstorno_ret_qs2qd 1135826 1135826 0 0.00 0 26235 33 0 0
sp_kill_qty_storno 598345 598345 0 0.00 0 26382 71 0 0
sp_lock_selected_doc 20640 20640 0 0.00 0 7549 5 0 0
sp_make_cost_storno 27727 27727 0 0.00 0 25379 60 0 0
sp_multiply_rows_for_pdistr 27727 27727 0 0.00 0 10852 29 0 0
sp_multiply_rows_for_qdistr 20803 20803 0 0.00 0 22829 154 0 0
sp_pay_from_customer 7048 7048 0 0.00 4 18996 123 0 0
sp_pay_to_supplier 5885 5885 0 0.00 5 17484 151 0 0
sp_payment_common 12933 12933 0 0.00 1 6905 23 0 0
sp_qd_handle_on_cancel_clo 1852 1852 0 0.00 0 6494 330 0 0
sp_qd_handle_on_invoice_upd_sts 10714 10714 0 0.00 0 21261 329 0 0
sp_qd_handle_on_reserve_upd_sts 9794 9794 0 0.00 0 21766 75 0 0
sp_reserve_write_off 9794 9794 0 0.00 6 21792 198 0 0
sp_supplier_invoice 5570 5570 0 0.00 6 86265 3579 0 0
sp_supplier_order 5452 5452 0 0.00 5 45552 1449 0 0
srv_aggregate_perf_data 98 97 1 1.02 2481 352439 21942 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2734 117351 42473 0 0
srv_find_qd_qs_mism 1057047 1057047 0 0.00 0 17680 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 22 89213 1231 0 0
srv_make_money_saldo 823 823 0 0.00 0 8301 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 137148 601426 353923 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 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 5004 5004 0 0.00 0 16768 199 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 3890 90 0 0
t$perf-norm:sp_cancel_client_order 1852 1852 0 0.00 0 6859 30 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 3647 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 656 656 0 0.00 0 1940 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 5288 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 1485 13 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 23420 1880 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 4377 14 0 0
t$perf-norm:sp_client_order 20804 20804 0 0.00 0 18571 23 0 0
t$perf-norm:sp_customer_reserve 1058 1058 0 0.00 0 4787 14 0 0
t$perf-norm:sp_pay_from_customer 7048 7048 0 0.00 0 10363 13 0 0
t$perf-norm:sp_pay_to_supplier 5885 5885 0 0.00 0 5764 13 0 0
t$perf-norm:sp_reserve_write_off 9794 9794 0 0.00 0 15544 17 0 0
t$perf-norm:sp_supplier_invoice 5570 5570 0 0.00 0 16965 24 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 5898 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 442 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 96 2 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 4299 30 0 0
t$perf-norm:srv_make_money_saldo 823 823 0 0.00 0 3350 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 11 5 0 0
v_add_invoice_to_stock 10224 10000 224 2.19 0 9088 22 0 224
v_all_customers 38290 38290 0 0.00 0 132 1 0 0
v_all_suppliers 30554 30554 0 0.00 0 134 1 0 0
v_all_wares 41606 41606 0 0.00 0 229 1 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 2981 14 0 0
v_cancel_client_order 3704 3704 0 0.00 0 4612 8 0 0
v_cancel_customer_prepayment 1312 1312 0 0.00 0 3583 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 6381 11 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1635 7 0 0
v_cancel_supplier_invoice 806 798 8 0.99 0 4709 28 0 8
v_cancel_supplier_order 856 856 0 0.00 0 2488 6 0 0
v_cancel_write_off 1990 1990 0 0.00 0 3322 14 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 2747 2 0 0
v_max_id_clo_res 1058 1058 0 0.00 0 3374 16 0 0
v_max_id_ord_sup 5567 5567 0 0.00 0 3627 5 0 0
v_max_non_paid_invoice 1626 1626 0 0.00 0 1841 4 0 0
v_max_non_paid_realizn 3546 3546 0 0.00 0 1887 4 0 0
v_min_id_clo_ord 5451 5451 0 0.00 0 3228 8 0 0
v_min_id_clo_res 1058 1058 0 0.00 0 3779 11 0 0
v_min_id_ord_sup 5567 5567 0 0.00 0 5048 7 0 0
v_min_non_paid_invoice 5885 5885 0 0.00 0 6895 17 0 0
v_min_non_paid_realizn 7048 7048 0 0.00 0 5689 16 0 0
v_our_firm 10320 10320 0 0.00 0 131 1 0 0
v_reserve_write_off 19588 19588 0 0.00 0 8525 8 0 0
x_lock_depdocs_on_canc_invoice 11592 11592 0 0.00 0 12112 119 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 10867 352 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 45453 1363 0 0
x_make_qstorno_1200_2000 5570 5570 0 0.00 3 86239 3413 0 0
x_make_qstorno_2100_3300 458844 458844 0 0.00 0 70692 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-06-2022 16:39:38 to 08-06-2022 16:39:42.

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:28 13 8192000000 8801785856 8814034944 6675824 0.081 3 1 2 0 6776752 58144 42784
14:09:29 18232 8192000000 9889744064 10197258240 975872944 10.644 100 90 180 0 1005920144 6035632 24011568
14:10:51 20812 8192000000 9927592128 10246029312 1011405104 10.989 100 87 174 0 1042649568 8239360 23005104
14:12:14 13900 8192000000 9969560496 10301263872 1052026832 11.380 100 78 156 0 1080389328 8930960 19431536
14:13:37 15552 8192000000 10018151648 10363244544 1103888144 11.875 100 78 155 0 1133100144 10981632 18230368
14:14:57 8127 8192000000 10079224912 10434924544 1161514704 12.417 100 83 166 1 1193618432 13177872 18925856
14:16:09 5484 8192000000 10119856064 10487885824 1211071664 12.879 100 81 159 1 1241622592 12885872 17665056
14:17:17 2086 8192000000 10154474864 10523959296 1235709600 13.107 100 85 170 3 1268286288 14410608 18166080
14:18:21 1481 8192000000 10188339424 10565455872 1270474928 13.426 100 93 184 4 1303846864 12898544 20473392
14:19:24 1458 8192000000 10205460528 10586488832 1284809328 13.557 100 99 199 0 1321611520 14918352 21883840
14:20:26 20066 8192000000 10201580624 10598465536 1294415168 13.644 100 51 101 4 1312904464 7253440 11235856
14:21:49 7200 8192000000 10191784336 10604986368 1300861648 13.703 100 32 63 0 1319619696 11493792 7264256
14:22:57 11584 8192000000 10195886640 10613694464 1312570752 13.809 100 14 26 2 1320748112 5336480 2840880
14:24:09 10823 8192000000 10217587168 10628874240 1324363840 13.916 100 27 53 0 1336797616 6480752 5953024
14:25:23 5065 8192000000 10239147232 10642051072 1328907408 13.957 100 64 129 2 1350863888 6661408 15295072
14:26:28 5727 8192000000 10228234752 10646126592 1331965440 13.985 100 29 58 1 1345210672 7069984 6175248
14:27:38 6154 8192000000 10220662624 10642833408 1333945152 14.003 100 26 52 1 1346082368 6112144 6025072
14:28:46 7417 8192000000 10215946736 10633179136 1324546320 13.918 100 32 63 0 1339012224 7399376 7066528
14:29:57 7592 8192000000 10192820096 10595266560 1287531264 13.582 99 33 64 1 1301552464 5861392 8159808
14:31:11 8117 8192000000 10135666592 10526367744 1189035440 12.674 95 20 39 1 1199118464 5329344 4753680
14:32:19 50195 8192000000 10125369312 10523709440 1203565344 12.809 96 36 71 2 1217800912 5254464 8981104
14:34:13 6504 8192000000 10157673056 10562744320 1264961616 13.375 100 22 43 3 1275124672 5351184 4811872
14:35:20 11356 8192000000 10179805136 10591805440 1283285808 13.543 100 16 32 0 1291917184 5038560 3592816
14:36:32 8020 8192000000 10192641632 10605641728 1297535920 13.673 100 27 53 3 1313175488 9097248 6542320
14:37:43 30901 8192000000 10212377888 10621198336 1309784512 13.784 100 34 69 1 1323422448 5778512 7859424
14:39:26 18769 8192000000 10252301424 10655232000 1332881024 13.993 100 56 109 0 1355703440 10000784 12821632
14:40:48 20655 8192000000 10265691104 10673848320 1347337984 14.124 100 54 107 3 1374120000 14553360 12228656
14:42:10 22065 8192000000 10271742912 10688356352 1357029056 14.211 100 30 58 2 1377374448 13401328 6944064
14:43:36 8373 8192000000 10282670400 10704289792 1371858032 14.344 100 39 78 2 1396835360 15737856 9239472
14:44:51 9865 8192000000 10298294272 10712363008 1379201392 14.409 100 53 105 2 1409044672 17812656 12030624
14:46:08 4427 8192000000 10317669440 10730840064 1391093856 14.516 100 67 132 1 1425389840 18731520 15564464
14:47:21 11379 8192000000 10322976032 10739855360 1398761024 14.584 100 75 148 1 1436160640 20463376 16936240
14:48:37 2732 8192000000 10339542432 10757623808 1405622928 14.645 100 67 134 1 1447685552 25341872 16720752
14:49:45 5539 8192000000 10337593824 10755923968 1386920080 14.478 99 65 130 0 1435577232 32134928 16522224
14:51:06 6241 8192000000 10353292880 10770362368 1408952384 14.675 100 71 144 0 1459492576 33304736 17235456
14:52:18 3108 8192000000 10364355552 10775859200 1410331328 14.687 100 81 162 1 1464361120 34305520 19724272
14:53:26 7527 8192000000 10347134592 10766368768 1400018000 14.595 100 58 114 0 1450812688 36466208 14328480
14:54:45 5383 8192000000 10359589984 10776395776 1396384816 14.563 99 73 142 2 1451026704 36306048 18335840
14:55:54 3120 8192000000 10343132912 10756128768 1383288832 14.446 99 73 146 0 1442797728 41304864 18204032
14:57:00 6881 8192000000 10304245216 10729635840 1370128640 14.328 99 34 68 2 1406040288 26780624 9131024
14:58:12 4915 8192000000 10347595200 10758512640 1390482048 14.510 100 68 135 2 1445218240 38082672 16653520
14:59:21 16305 8192000000 10323229856 10745180160 1378454592 14.403 100 35 70 0 1426488272 38231888 9801792
15:00:46 5105 8192000000 10330704000 10745856000 1369769024 14.325 100 67 134 0 1433985680 46573696 17642960
15:02:05 19042 8192000000 10315630448 10738884608 1363350032 14.267 99 44 88 4 1423871760 47767024 12754704
15:03:52 18741 8192000000 10321033424 10746785792 1369934576 14.326 100 31 62 2 1423018288 44211152 8872560
15:05:41 30696 8192000000 10320799616 10760335360 1375963088 14.380 100 17 34 0 1425472560 44147632 5361840
15:07:12 49146 8192000000 10307228928 10756403200 1373605056 14.359 100 23 46 0 1418199728 38616272 5978400
15:09:04 33167 8192000000 10287443120 10752393216 1383290624 14.446 100 24 48 0 1409775344 20919040 5565680
15:12:10 103910 8192000000 10280736048 10738642944 1367407680 14.304 99 17 34 0 1381214624 10324016 3482928
15:14:55 25127 8192000000 10286579152 10731483136 1381943760 14.434 100 13 26 0 1391426416 7089456 2393200
15:16:21 67265 8192000000 10292032576 10738761728 1375473840 14.376 99 28 55 0 1392478336 10254384 6750112
15:18:29 38984 8192000000 10299790944 10752802816 1376435776 14.385 99 22 44 0 1396868432 14975408 5457248
15:20:49 48705 8192000000 10310797008 10761080832 1398948736 14.586 100 36 72 0 1419752944 12104784 8699424
15:22:45 27870 8192000000 10311294944 10751180800 1383005360 14.443 99 43 87 3 1401974528 8984784 9984384
15:24:16 65072 8192000000 10294862608 10757263360 1383984224 14.452 99 28 55 1 1399738912 9133616 6621072
15:26:37 60968 8192000000 10291906960 10757128192 1375882352 14.380 98 11 22 1 1390057552 11427904 2747296
15:28:38 104296 8192000000 10303063456 10770780160 1406942832 14.657 100 13 27 0 1419833728 9774160 3116736
15:31:52 67236 8192000000 10290175600 10742136832 1366992240 14.300 97 29 58 0 1388422896 14555008 6875648
15:34:04 61545 8192000000 10293367440 10761695232 1391652064 14.521 99 20 40 0 1411871248 15074416 5144768
15:36:05 46223 8192000000 10295308880 10761773056 1398471840 14.581 100 15 30 0 1413448752 11316240 3660672
15:37:58 85245 8192000000 10325981568 10782359552 1413184944 14.712 100 11 22 0 1423608080 7888480 2534656
15:40:23 58480 8192000000 10278648752 10743836672 1336297968 14.024 95 17 34 0 1351187424 11247184 3642272
15:42:22 50500 8192000000 10294186656 10754404352 1355896304 14.200 97 17 34 1 1370229216 10665648 3667264
15:44:13 36308 8192000000 10285072592 10726596608 1302510736 13.718 93 36 69 2 1323786336 13426928 7848672
15:45:51 69100 8192000000 10258396656 10693992448 1290981600 13.613 95 19 38 0 1305815760 10530256 4303904
15:48:03 55376 8192000000 10278166720 10729504768 1364592880 14.279 100 25 49 1 1382921680 12801936 5526864
15:50:05 90441 8192000000 10265383280 10690215936 1262187536 13.350 92 15 30 0 1280222960 14529600 3505824
15:52:45 73945 8192000000 10233205168 10669330432 1273814784 13.457 94 27 54 0 1289768544 9570384 6383376
15:55:06 32080 8192000000 10256141840 10705043456 1328896400 13.957 98 21 42 0 1345488128 11482320 5109408
15:56:39 35816 8192000000 10249490032 10699190272 1324729104 13.920 98 17 32 3 1335460032 7475328 3255600
15:58:14 65228 8192000000 10243994896 10693976064 1338368816 14.043 99 17 34 0 1352773808 10349824 4055168
16:00:20 33483 8192000000 10251028064 10715189248 1359469104 14.233 100 4 8 1 1370250544 9931072 850368
16:02:42 59774 8192000000 10270176096 10728534016 1370257616 14.329 100 17 34 0 1384440256 10425472 3757168
16:04:43 30333 8192000000 10258197456 10708287488 1338451232 14.043 98 10 20 0 1347725520 6923888 2350400
16:06:21 44018 8192000000 10275549488 10724913152 1361627200 14.252 99 23 46 0 1375969296 8531072 5811024
16:08:12 59447 8192000000 10288206720 10742566912 1365246480 14.284 99 14 28 1 1380555904 12340800 2968624
16:10:13 62659 8192000000 10295128768 10753130496 1371740576 14.343 99 11 22 0 1384666912 10878736 2047600
16:12:26 56241 8192000000 10322136896 10769862656 1397701216 14.575 100 18 36 1 1410796688 9570384 3525088
16:14:57 117351 8192000000 10318316496 10778120192 1360123760 14.238 97 21 42 0 1388325104 23807168 4394176
16:17:56 99738 8192000000 10305207040 10760208384 1388300112 14.491 99 23 46 1 1403801456 10090960 5410384
16:20:38 46449 8192000000 10314079952 10757648384 1361198448 14.248 97 31 62 0 1382240672 13438256 7603968
16:22:50 45179 8192000000 10308026976 10774409216 1403015632 14.622 100 6 12 0 1418654816 14508864 1130320
16:24:36 40466 8192000000 10274029088 10727084032 1359138784 14.230 97 16 32 0 1374457760 11613392 3705584
16:26:17 72783 8192000000 10290369056 10743590912 1383733520 14.450 99 21 43 2 1401118112 12371152 5013440
16:29:34 57802 8192000000 10282603744 10740637696 1374015856 14.363 99 23 45 0 1387568224 8462944 5089424
16:31:33 61910 8192000000 10298594656 10749472768 1340855888 14.065 96 14 28 0 1353660096 9550064 3254144
16:33:36 69311 8192000000 10277314768 10738819072 1325017104 13.922 96 9 18 0 1342625024 15673600 1934320
16:35:46 32740 8192000000 10256606512 10689077248 1290770544 13.611 96 29 58 1 1308328112 11129920 6427648
16:37:22 45615 8192000000 10259818288 10690809856 1320733856 13.883 99 41 82 0 1343361712 13862304 8765552

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-06-2022 16:39:44 to 08-06-2022 16:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 112 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-06-2022 16:40:00 to 08-06-2022 16:40:02.

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          255975
MON$OLDEST_ACTIVE               255976
MON$OLDEST_SNAPSHOT             255976
MON$NEXT_TRANSACTION            255976
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                       2039648
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
--- end of diff output ---

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

08.06.22 16:40:03. 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-06-08 16:40:05 No severe PSQL-related problems occured
08.06.22 16:40:06. End of report.