Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.03.2023 01: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 15329172 kB
MemAvailable 30392940 kB
Buffers 0 kB
Cached 16358040 kB
SwapCached 3236 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-03-16 01:38:40
DTS_END                         2023-03-16 01:38:40.4250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40068
TRN_ID                          249162
ATT_ID                          737
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: 9134.64 23573 1096157
customer order: creation 181.12 313 21734
customer order: refuse 15.31 351 1837
order to supplier: creation 47.57 1237 5708
order to supplier: removal 3.29 84905 395
invoice (draft): creation 46.86 3393 5623
invoice (draft): removal 49.26 723 5911
invoice accept: apply 43.65 16996 5238
invoice accept: cancel 44.63 782 5355
customer reserve: creation 3973.67 170 476840
customer reserve: removal 4440.44 48 532853
realization accept: apply 85.30 168 10236
realization accept: cancel 69.30 110 8316
payment from customer: creation 61.22 98 7346
payment from customer: removal 5.94 67 713
payment to supplier: creation 50.67 119 6080
payment to supplier: removal 3.68 76 441
service: total inventory turnovers 5.14 1052 617
service: total monetary turnovers 6.83 23 820
service: aggregate perf. data 0.75 25534 90
service: refresh index statistics 0.03 335287 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-03-2023 01:39:54 to 16-03-2023 01:39:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 538
TEST_TIME 2 1920
TEST_TIME 3 2581
TEST_TIME 4 4242
TEST_TIME 5 4410
TEST_TIME 6 4721
TEST_TIME 7 5134
TEST_TIME 8 6245
TEST_TIME 9 6084
TEST_TIME 10 6159
TEST_TIME 11 6050
TEST_TIME 12 6088
TEST_TIME 13 6534
TEST_TIME 14 6501
TEST_TIME 15 6505
TEST_TIME 16 6592
TEST_TIME 17 6821
TEST_TIME 18 6895
TEST_TIME 19 7038
TEST_TIME 20 7370
TEST_TIME 21 8120
TEST_TIME 22 8038
TEST_TIME 23 8173
TEST_TIME 24 8098
TEST_TIME 25 8386
TEST_TIME 26 9071
TEST_TIME 27 9362
TEST_TIME 28 9228
TEST_TIME 29 9371
TEST_TIME 30 9475
TEST_TIME 31 9788
TEST_TIME 32 9739
TEST_TIME 33 9725
TEST_TIME 34 9574
TEST_TIME 35 9559
TEST_TIME 36 9351
TEST_TIME 37 9244
TEST_TIME 38 9364
TEST_TIME 39 9237
TEST_TIME 40 9242
TEST_TIME 41 9213
TEST_TIME 42 9168
TEST_TIME 43 9105
TEST_TIME 44 9030
TEST_TIME 45 8958
TEST_TIME 46 8778
TEST_TIME 47 8884
TEST_TIME 48 8864
TEST_TIME 49 8829
TEST_TIME 50 8890
TEST_TIME 51 8881
TEST_TIME 52 8961
TEST_TIME 53 9012
TEST_TIME 54 9021
TEST_TIME 55 9001
TEST_TIME 56 8997
TEST_TIME 57 9069
TEST_TIME 58 9000
TEST_TIME 59 9327
TEST_TIME 60 9371
TEST_TIME 61 9383
TEST_TIME 62 9391
TEST_TIME 63 9266
TEST_TIME 64 9221
TEST_TIME 65 9177
TEST_TIME 66 9171
TEST_TIME 67 9159
TEST_TIME 68 9090
TEST_TIME 69 9088
TEST_TIME 70 9093
TEST_TIME 71 9074
TEST_TIME 72 9106
TEST_TIME 73 9096
TEST_TIME 74 9128
TEST_TIME 75 9128
TEST_TIME 76 9222
TEST_TIME 77 9218
TEST_TIME 78 9295
TEST_TIME 79 9322
TEST_TIME 80 9322
TEST_TIME 81 9317
TEST_TIME 82 9228
TEST_TIME 83 9190
TEST_TIME 84 9157
TEST_TIME 85 9129
TEST_TIME 86 9163
TEST_TIME 87 9195
TEST_TIME 88 9149
TEST_TIME 89 9131
TEST_TIME 90 9072
TEST_TIME 91 9021
TEST_TIME 92 9010
TEST_TIME 93 9086
TEST_TIME 94 9102
TEST_TIME 95 9197
TEST_TIME 96 9198
TEST_TIME 97 9191
TEST_TIME 98 9206
TEST_TIME 99 9234
TEST_TIME 100 9260
TEST_TIME 101 9273
TEST_TIME 102 9370
TEST_TIME 103 9356
TEST_TIME 104 9357
TEST_TIME 105 9295
TEST_TIME 106 9252
TEST_TIME 107 9287
TEST_TIME 108 9232
TEST_TIME 109 9231
TEST_TIME 110 9196
TEST_TIME 111 9223
TEST_TIME 112 9211
TEST_TIME 113 9180
TEST_TIME 114 9135
TEST_TIME 115 9108
TEST_TIME 116 9094
TEST_TIME 117 9137
TEST_TIME 118 9135
TEST_TIME 119 9137
TEST_TIME 120 9135

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-03-2023 01:39:58 to 16-03-2023 01:40:01.

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 1539398 1539398 0 0.00 0 24079 6 0 0
doc_list_biud 1539398 1539398 0 0.00 0 32024 22 0 0
sp_add_invoice_to_stock 5380 5238 142 2.63 29 188016 16996 0 142
sp_cancel_adding_invoice 5355 5355 0 0.00 4 71815 783 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 22381 351 0 0
sp_cancel_customer_reserve 532853 532853 0 0.00 0 32026 49 0 0
sp_cancel_pay_from_customer 713 713 0 0.00 2 2704 68 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 2428 76 0 0
sp_cancel_supplier_invoice 5924 5911 13 0.21 2 32829 724 0 13
sp_cancel_supplier_order 395 395 0 0.00 5 799906 84906 0 0
sp_cancel_write_off 8316 8316 0 0.00 1 24081 111 0 0
sp_client_order 21734 21734 0 0.00 3 28334 313 0 0
sp_customer_reserve 476840 476840 0 0.00 0 57018 170 0 0
sp_fill_shopping_cart 33049 33049 0 0.00 1 35702 38 0 0
sp_fill_shopping_cart_clo_res 476839 476839 0 0.00 0 22238 13 0 0
sp_get_clo_for_invoice 5237 5237 0 0.00 0 31815 282 0 0
sp_kill_cost_storno 14825 14825 0 0.00 0 24079 54 0 0
sp_kill_qstorno_ret_qs2qd 1080328 1080328 0 0.00 0 32017 27 0 0
sp_kill_qty_storno 570140 570140 0 0.00 0 32024 60 0 0
sp_lock_selected_doc 21430 21430 0 0.00 0 8782 6 0 0
sp_make_cost_storno 28899 28899 0 0.00 0 14532 46 0 0
sp_multiply_rows_for_pdistr 28899 28899 0 0.00 0 8411 25 0 0
sp_multiply_rows_for_qdistr 21734 21734 0 0.00 0 20192 137 0 0
sp_pay_from_customer 7346 7346 0 0.00 4 10033 99 0 0
sp_pay_to_supplier 6080 6080 0 0.00 4 10442 120 0 0
sp_payment_common 13426 13426 0 0.00 1 7944 26 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 22366 327 0 0
sp_qd_handle_on_invoice_upd_sts 10592 10592 0 0.00 0 31958 348 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 15305 62 0 0
sp_reserve_write_off 10236 10236 0 0.00 6 15528 169 0 0
sp_supplier_invoice 5623 5623 0 0.00 5 112670 3394 0 0
sp_supplier_order 5708 5708 0 0.00 4 20524 1238 0 0
srv_aggregate_perf_data 90 90 0 0.00 2691 851105 25535 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2798 123657 45143 0 0
srv_find_qd_qs_mism 1047486 1047486 0 0.00 0 12669 5 0 0
srv_make_invnt_saldo 617 617 0 0.00 43 40271 1052 0 0
srv_make_money_saldo 820 820 0 0.00 1 384 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 174228 487224 335288 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5238 5238 0 0.00 0 12613 166 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 5227 108 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 2078 13 0 0
t$perf-norm:sp_cancel_customer_reserve 758 758 0 0.00 0 3094 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 713 713 0 0.00 0 1748 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 209 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 3782 24 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 20062 1475 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 10761 23 0 0
t$perf-norm:sp_client_order 21734 21734 0 0.00 0 8684 15 0 0
t$perf-norm:sp_customer_reserve 1084 1084 0 0.00 0 2392 6 0 0
t$perf-norm:sp_pay_from_customer 7346 7346 0 0.00 0 9288 10 0 0
t$perf-norm:sp_pay_to_supplier 6080 6080 0 0.00 0 3982 8 0 0
t$perf-norm:sp_reserve_write_off 10236 10236 0 0.00 0 5635 9 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 7961 21 0 0
t$perf-norm:sp_supplier_order 5708 5708 0 0.00 0 3028 15 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 395 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 152 4 0 0
t$perf-norm:srv_make_invnt_saldo 617 617 0 0.00 0 831 14 0 0
t$perf-norm:srv_make_money_saldo 820 820 0 0.00 0 3241 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10758 10474 284 2.63 0 6318 19 0 284
v_all_customers 39154 39154 0 0.00 0 19 1 0 0
v_all_suppliers 31070 31070 0 0.00 0 28 1 0 0
v_all_wares 43468 43468 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1905 9 0 0
v_cancel_client_order 3674 3674 0 0.00 0 2690 6 0 0
v_cancel_customer_prepayment 1426 1426 0 0.00 0 1231 5 0 0
v_cancel_customer_reserve 1516 1516 0 0.00 0 2363 7 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 1835 8 0 0
v_cancel_supplier_invoice 852 826 26 3.05 0 1073 13 0 26
v_cancel_supplier_order 790 790 0 0.00 0 1020 5 0 0
v_cancel_write_off 2008 2008 0 0.00 0 4589 21 0 0
v_max_id_clo_ord 5708 5708 0 0.00 0 1302 1 0 0
v_max_id_clo_res 1084 1084 0 0.00 0 19625 36 0 0
v_max_id_ord_sup 5606 5606 0 0.00 0 3440 4 0 0
v_max_non_paid_invoice 1859 1859 0 0.00 0 2670 5 0 0
v_max_non_paid_realizn 4078 4078 0 0.00 0 3475 5 0 0
v_min_id_clo_ord 5708 5708 0 0.00 0 5171 8 0 0
v_min_id_clo_res 1084 1084 0 0.00 0 2677 10 0 0
v_min_id_ord_sup 5606 5606 0 0.00 0 4960 8 0 0
v_min_non_paid_invoice 6080 6080 0 0.00 0 5616 17 0 0
v_min_non_paid_realizn 7346 7346 0 0.00 0 7912 20 0 0
v_our_firm 10850 10850 0 0.00 0 32 1 0 0
v_reserve_write_off 20472 20472 0 0.00 0 4901 7 0 0
x_lock_depdocs_on_canc_invoice 10853 10853 0 0.00 0 30163 123 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 9865 347 0 0
x_make_qstorno_1000_1200 5708 5708 0 0.00 1 20519 1162 0 0
x_make_qstorno_1200_2000 5623 5623 0 0.00 2 106587 3238 0 0
x_make_qstorno_2100_3300 476840 476840 0 0.00 0 57016 154 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 16-03-2023 01:40:02 to 16-03-2023 01:40:07.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:44 14 8192000000 8824144224 8844259328 23993168 0.292 9 5 5 0 24228448 96016 139264
23:09:45 22184 8192000000 9909971104 10222911488 997528432 10.855 100 87 174 0 1026802144 6578624 22695088
23:11:11 20201 8192000000 9957311696 10280767488 1034321312 11.210 100 88 176 0 1065957408 8992400 22643696
23:12:34 14458 8192000000 9981536544 10312065024 1060021024 11.457 100 84 168 0 1091478144 10764304 20692816
23:13:52 13742 8192000000 10017428544 10362044416 1102040800 11.857 100 77 154 0 1131562384 11082128 18439456
23:15:09 10160 8192000000 10068386768 10424467456 1151907296 12.327 100 68 136 1 1179559856 12339984 15312576
23:16:24 3125 8192000000 10120166736 10486939648 1208886288 12.859 100 80 160 0 1239273552 11891600 18495664
23:17:29 2368 8192000000 10159920768 10533249024 1243153200 13.175 100 83 166 2 1275830704 14350704 18326800
23:18:33 1128 8192000000 10188058768 10568495104 1268586912 13.409 100 87 174 0 1301044928 13246832 19211184
23:19:35 1440 8192000000 10211234688 10602377216 1298593904 13.682 100 81 162 0 1329022096 12061136 18367056
23:20:37 6337 8192000000 10198216800 10603945984 1306831152 13.757 100 35 70 0 1323122560 8834128 7457280
23:21:45 5310 8192000000 10217046512 10627653632 1317990144 13.859 100 27 54 1 1333959568 10178928 5790496
23:22:50 13757 8192000000 10210204496 10629881856 1325357824 13.925 100 13 26 0 1334122800 6092032 2672944
23:24:05 62186 8192000000 10209563280 10639491072 1328793472 13.956 100 33 66 0 1341614784 5079984 7741328
23:26:15 4902 8192000000 10228674608 10650103808 1338600176 14.045 100 26 52 0 1350160064 5572176 5987712
23:27:21 8664 8192000000 10227672224 10643714048 1314944576 13.831 98 24 49 0 1327411328 6332656 6134096
23:28:30 54465 8192000000 10199449056 10615242752 1268844288 13.411 96 24 48 1 1279251216 4968656 5438272
23:30:36 5429 8192000000 10156515936 10556796928 1249181456 13.231 98 21 43 0 1259304576 5172944 4950176
23:31:43 8858 8192000000 10142993232 10546462720 1233996384 13.091 98 20 40 0 1243953648 4893232 5064032
23:32:55 11803 8192000000 10139823344 10540539904 1238422400 13.132 99 18 36 1 1247649968 5185536 4042032
23:34:07 35512 8192000000 10167571280 10572328960 1261356544 13.342 99 23 46 0 1272386224 6211760 4817920
23:35:44 9318 8192000000 10183816720 10596589568 1291067936 13.614 100 21 42 0 1301172048 5576960 4527152
23:36:55 11435 8192000000 10196517568 10615513088 1303253504 13.725 100 11 20 1 1318460368 13383360 1823504
23:38:10 16385 8192000000 10211942400 10631733248 1324290880 13.916 100 27 49 2 1335086560 4938448 5857232
23:39:29 63565 8192000000 10244472928 10661670912 1334403808 14.007 100 44 86 1 1358432048 15455136 8573104
23:41:38 5013 8192000000 10266977216 10680135680 1356622000 14.207 100 51 100 0 1378446448 10943792 10880656
23:42:54 19261 8192000000 10287490816 10705498112 1372859696 14.353 100 57 113 0 1400426128 14919056 12647376
23:44:17 10412 8192000000 10291946480 10714570752 1381193712 14.427 100 46 90 0 1404939440 12478144 11267584
23:45:34 19015 8192000000 10279609408 10698641408 1366304944 14.294 100 49 97 0 1392675200 14731824 11638432
23:47:07 17132 8192000000 10283605680 10709590016 1375861680 14.380 100 56 111 1 1399164576 10307600 12995296
23:48:32 28764 8192000000 10275835776 10710364160 1342567792 14.081 97 32 64 0 1362013728 11999728 7446208
23:50:07 9092 8192000000 10299518320 10719522816 1380510320 14.421 100 50 99 2 1405762400 13639616 11612464
23:51:22 7700 8192000000 10305108272 10732613632 1376479728 14.385 99 50 100 0 1408480416 20187776 11812912
23:52:35 6811 8192000000 10306515296 10724294656 1380111440 14.418 100 66 132 0 1415735600 19925056 15699104
23:53:45 2798 8192000000 10303609504 10717790208 1370803456 14.334 100 63 126 0 1408906192 22884432 15218304
23:54:58 2797 8192000000 10325981568 10732838912 1371190624 14.338 99 77 149 5 1413654032 25504624 16958784
23:56:07 4653 8192000000 10314750416 10726035456 1363691408 14.270 99 61 121 0 1405574832 26519024 15364400
23:57:16 5240 8192000000 10320142352 10728480768 1371600896 14.341 99 73 145 0 1422915712 33770144 17544672
23:58:27 3680 8192000000 10322991344 10726371328 1365365088 14.285 99 80 158 1 1422551696 38492848 18693760
23:59:33 15242 8192000000 10293710064 10724134912 1360170624 14.239 99 34 69 0 1401803008 32600944 9031440
00:00:53 16886 8192000000 10289635488 10708238336 1348572128 14.135 99 47 91 1 1394372848 34664480 11136240
00:02:20 54167 8192000000 10291421808 10716487680 1341681952 14.073 99 27 52 0 1387300848 38245440 7373456
00:04:17 26275 8192000000 10273450592 10715512832 1356382256 14.205 100 15 30 0 1387461728 27222976 3856496
00:05:48 28949 8192000000 10266233648 10694230016 1335854448 14.020 99 26 48 1 1361193856 19599968 5739440
00:07:21 32195 8192000000 10273469392 10708045824 1354677712 14.190 100 17 34 0 1373984352 14622720 4683920
00:09:06 39826 8192000000 10281920704 10716897280 1362890576 14.263 100 11 22 0 1385903504 20461328 2551600
00:10:52 30427 8192000000 10293942288 10718400512 1359661728 14.234 99 35 70 1 1383218656 15683616 7873312
00:12:29 61466 8192000000 10296701840 10728378368 1376040768 14.381 100 42 84 0 1393441232 7993488 9406976
00:14:33 85496 8192000000 10290660640 10737332224 1359666128 14.234 98 31 61 1 1376531456 9973584 6891744
00:17:04 55056 8192000000 10290627056 10741690368 1391383088 14.518 100 18 36 0 1403151904 7523552 4245264
00:19:03 25287 8192000000 10282883712 10745266176 1390208128 14.508 100 10 20 0 1401302448 8884688 2209632
00:20:30 45324 8192000000 10292708880 10748055552 1381594864 14.431 99 24 47 0 1396263184 9312272 5356048
00:22:25 42624 8192000000 10291419184 10745974784 1382965584 14.443 99 20 40 0 1397801376 10360624 4475168
00:24:08 93192 8192000000 10302511216 10761904128 1403224192 14.624 100 30 60 0 1424884512 14224384 7435936
00:26:43 55596 8192000000 10290211040 10747674624 1382254880 14.437 99 12 24 0 1396584048 11567664 2761504
00:29:05 72666 8192000000 10286741056 10743803904 1357403936 14.214 97 19 38 0 1372295920 10400736 4491248
00:31:17 86395 8192000000 10271997424 10714488832 1273267856 13.452 92 19 38 0 1285823808 8055136 4500816
00:33:55 39473 8192000000 10286007440 10725515264 1365301248 14.285 100 26 52 0 1392064688 20375600 6387840
00:35:40 61645 8192000000 10296907600 10741932032 1356838576 14.209 99 13 26 0 1387009280 27144016 3026688
00:37:57 62884 8192000000 10233000080 10674929664 1215518192 12.920 89 9 16 0 1228163424 11395392 1249840
00:40:00 52348 8192000000 10244213648 10682404864 1326866064 13.939 99 28 56 0 1343200752 10564464 5770224
00:41:57 56858 8192000000 10285202688 10716839936 1360797552 14.245 100 50 100 0 1383814160 11840400 11176208
00:43:54 74825 8192000000 10251542016 10711724032 1338686000 14.046 98 12 24 0 1352364768 10878096 2800672
00:46:09 42193 8192000000 10260142560 10696978432 1295174256 13.651 95 20 39 0 1305695728 5951232 4570240
00:47:54 33852 8192000000 10278985696 10716921856 1341127440 14.068 98 44 86 1 1361223888 10649344 9447104
00:49:32 81104 8192000000 10277113808 10728271872 1345022608 14.103 98 15 30 0 1358827568 10228912 3576048
00:51:54 39298 8192000000 10272415440 10718969856 1350208800 14.149 99 13 26 0 1370634384 17657776 2767808
00:53:33 82363 8192000000 10274173888 10732498944 1355310400 14.195 98 10 20 0 1367536464 10336304 1889760
00:56:00 103356 8192000000 10292858480 10727841792 1331255312 13.978 97 23 46 0 1353401328 17006528 5139488
00:59:01 68610 8192000000 10278817664 10734481408 1350825552 14.155 98 23 47 0 1369148736 13477424 4845760
01:01:14 56487 8192000000 10279446480 10726055936 1336304480 14.024 97 28 56 0 1349707056 7672416 5730160
01:03:10 42227 8192000000 10307843536 10760892416 1388931984 14.496 100 26 52 0 1415678224 20917280 5828960
01:04:53 44482 8192000000 10285533536 10747260928 1377872656 14.398 99 35 67 1 1393666864 8232192 7562016
01:06:41 62262 8192000000 10287173856 10752770048 1394609104 14.547 100 12 25 0 1404991040 7133376 3248560
01:08:51 63915 8192000000 10299859936 10756243456 1382379904 14.438 98 14 28 0 1397629760 12167648 3082208
01:11:06 74404 8192000000 10320521200 10778996736 1386352704 14.473 98 18 36 0 1406241072 15713936 4174432
01:13:33 60118 8192000000 10303043472 10760683520 1383908080 14.451 99 14 28 0 1397125440 10550736 2666624
01:15:35 25640 8192000000 10314968880 10769645568 1404912240 14.639 100 20 40 0 1421119264 11609984 4597040
01:17:10 15778 8192000000 10307084704 10761084928 1388877216 14.496 100 30 60 0 1407604624 12263424 6463984
01:19:03 123656 8192000000 10306553392 10778013696 1293301456 13.634 92 16 32 0 1327422512 30491888 3629168
01:22:06 87984 8192000000 10273166432 10705477632 1214520864 12.911 88 20 40 0 1231291040 12300448 4469728
01:25:28 73982 8192000000 10264835824 10724728832 1339901376 14.057 98 14 28 0 1356236544 13028880 3306288
01:27:46 43200 8192000000 10244325568 10694610944 1303715520 13.729 95 16 33 0 1313294224 6309536 3269168
01:30:11 58430 8192000000 10254629440 10721267712 1340777600 14.064 98 7 14 0 1353123456 10708784 1637072
01:32:14 57505 8192000000 10259523152 10718031872 1349697648 14.145 99 12 22 0 1358899296 6823440 2378208
01:34:12 70150 8192000000 10266837744 10735452160 1360694608 14.244 99 12 22 0 1373519616 10353936 2471072
01:36:26 35418 8192000000 10277627840 10724761600 1351885200 14.164 99 24 47 0 1365405040 8298864 5220976
01:38:34 21626 8192000000 9040913184 9254461440 206250912 2.455 16 2 4 0 212923744 6587760 85072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-03-2023 01:40:08 to 16-03-2023 01:40:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-03-2023 01:40:25 to 16-03-2023 01:40:27.

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          249755
MON$OLDEST_ACTIVE               250755
MON$OLDEST_SNAPSHOT             250755
MON$NEXT_TRANSACTION            250755
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                       2023872
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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'

16.03.23 01:40:28. 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-03-16 01:40:30 No severe PSQL-related problems occured
16.03.23 01:40:32. End of report.