Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16006152 kB
MemAvailable 30641356 kB
Buffers 0 kB
Cached 15665712 kB
SwapCached 3796 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.8
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 2021-10-13 22:37:51
DTS_END                         2021-10-13 22:37:51.7250
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/56192
TRN_ID                          235481
ATT_ID                          637
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: 9108.74 24020 1093049
customer order: creation 169.42 347 20330
customer order: refuse 14.81 365 1777
order to supplier: creation 45.62 1292 5474
order to supplier: removal 3.30 97407 396
invoice (draft): creation 45.10 3226 5412
invoice (draft): removal 51.74 751 6209
invoice accept: apply 41.07 17309 4928
invoice accept: cancel 46.52 837 5582
customer reserve: creation 3845.39 169 461447
customer reserve: removal 4548.82 56 545858
realization accept: apply 82.86 168 9943
realization accept: cancel 83.98 109 10078
payment from customer: creation 60.03 127 7204
payment from customer: removal 5.88 76 705
payment to supplier: creation 48.43 136 5811
payment to supplier: removal 3.61 85 433
service: total inventory turnovers 4.63 1133 556
service: total monetary turnovers 6.68 22 801
service: aggregate perf. data 0.84 20662 101
service: refresh index statistics 0.03 336121 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-10-2021 22:38:57 to 13-10-2021 22:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3719
TEST_TIME 2 4227
TEST_TIME 3 4951
TEST_TIME 4 4121
TEST_TIME 5 4585
TEST_TIME 6 4590
TEST_TIME 7 4893
TEST_TIME 8 4740
TEST_TIME 9 5022
TEST_TIME 10 5243
TEST_TIME 11 5081
TEST_TIME 12 5330
TEST_TIME 13 5613
TEST_TIME 14 6300
TEST_TIME 15 6689
TEST_TIME 16 7264
TEST_TIME 17 7566
TEST_TIME 18 7570
TEST_TIME 19 7781
TEST_TIME 20 8641
TEST_TIME 21 8577
TEST_TIME 22 8886
TEST_TIME 23 9184
TEST_TIME 24 9375
TEST_TIME 25 9753
TEST_TIME 26 9929
TEST_TIME 27 10419
TEST_TIME 28 10665
TEST_TIME 29 10445
TEST_TIME 30 10430
TEST_TIME 31 10398
TEST_TIME 32 10467
TEST_TIME 33 10181
TEST_TIME 34 10282
TEST_TIME 35 10175
TEST_TIME 36 10208
TEST_TIME 37 10114
TEST_TIME 38 10025
TEST_TIME 39 10113
TEST_TIME 40 9996
TEST_TIME 41 9847
TEST_TIME 42 9807
TEST_TIME 43 9711
TEST_TIME 44 9770
TEST_TIME 45 9631
TEST_TIME 46 9611
TEST_TIME 47 9549
TEST_TIME 48 9548
TEST_TIME 49 9579
TEST_TIME 50 9624
TEST_TIME 51 9643
TEST_TIME 52 9660
TEST_TIME 53 9684
TEST_TIME 54 9646
TEST_TIME 55 9651
TEST_TIME 56 9667
TEST_TIME 57 9546
TEST_TIME 58 9519
TEST_TIME 59 9462
TEST_TIME 60 9443
TEST_TIME 61 9373
TEST_TIME 62 9359
TEST_TIME 63 9385
TEST_TIME 64 9303
TEST_TIME 65 9257
TEST_TIME 66 9155
TEST_TIME 67 9196
TEST_TIME 68 9176
TEST_TIME 69 9204
TEST_TIME 70 9212
TEST_TIME 71 9251
TEST_TIME 72 9190
TEST_TIME 73 9272
TEST_TIME 74 9369
TEST_TIME 75 9359
TEST_TIME 76 9305
TEST_TIME 77 9337
TEST_TIME 78 9357
TEST_TIME 79 9350
TEST_TIME 80 9346
TEST_TIME 81 9270
TEST_TIME 82 9273
TEST_TIME 83 9201
TEST_TIME 84 9237
TEST_TIME 85 9184
TEST_TIME 86 9156
TEST_TIME 87 9111
TEST_TIME 88 9059
TEST_TIME 89 9035
TEST_TIME 90 9040
TEST_TIME 91 9036
TEST_TIME 92 9088
TEST_TIME 93 9085
TEST_TIME 94 9081
TEST_TIME 95 9109
TEST_TIME 96 9146
TEST_TIME 97 9174
TEST_TIME 98 9155
TEST_TIME 99 9110
TEST_TIME 100 9252
TEST_TIME 101 9221
TEST_TIME 102 9312
TEST_TIME 103 9283
TEST_TIME 104 9348
TEST_TIME 105 9387
TEST_TIME 106 9325
TEST_TIME 107 9253
TEST_TIME 108 9245
TEST_TIME 109 9226
TEST_TIME 110 9189
TEST_TIME 111 9142
TEST_TIME 112 9109
TEST_TIME 113 9134
TEST_TIME 114 9118
TEST_TIME 115 9123
TEST_TIME 116 9136
TEST_TIME 117 9117
TEST_TIME 118 9114
TEST_TIME 119 9092
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-10-2021 22:39:00 to 13-10-2021 22:39:04.

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 1518342 1518342 0 0.00 0 19358 6 0 0
doc_list_biud 1518342 1518342 0 0.00 0 47317 26 0 0
sp_add_invoice_to_stock 5075 4928 147 2.89 18 200719 17310 0 147
sp_cancel_adding_invoice 5582 5582 0 0.00 5 68413 837 0 0
sp_cancel_client_order 1777 1777 0 0.00 4 16015 365 0 0
sp_cancel_customer_reserve 545858 545858 0 0.00 1 47318 57 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 2 6450 77 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 3 5357 86 0 0
sp_cancel_supplier_invoice 6213 6209 4 0.06 1 29527 751 0 4
sp_cancel_supplier_order 396 396 0 0.00 8 1146283 97407 0 0
sp_cancel_write_off 10078 10078 0 0.00 1 18714 110 0 0
sp_client_order 20330 20330 0 0.00 3 26513 347 0 0
sp_customer_reserve 461447 461447 0 0.00 0 132911 169 0 0
sp_fill_shopping_cart 31206 31206 0 0.00 1 15280 37 0 0
sp_fill_shopping_cart_clo_res 461434 461434 0 0.00 0 16005 13 0 0
sp_get_clo_for_invoice 4915 4915 0 0.00 0 21221 330 0 0
sp_kill_cost_storno 16798 16798 0 0.00 0 16673 50 0 0
sp_kill_qstorno_ret_qs2qd 1108399 1108399 0 0.00 0 47316 31 0 0
sp_kill_qty_storno 584758 584758 0 0.00 0 47317 66 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 8732 5 0 0
sp_make_cost_storno 27873 27873 0 0.00 0 18898 56 0 0
sp_multiply_rows_for_pdistr 27873 27873 0 0.00 0 18889 27 0 0
sp_multiply_rows_for_qdistr 20330 20330 0 0.00 0 17898 158 0 0
sp_pay_from_customer 7204 7204 0 0.00 4 18910 128 0 0
sp_pay_to_supplier 5811 5811 0 0.00 4 28408 136 0 0
sp_payment_common 13015 13015 0 0.00 2 28397 29 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 16009 335 0 0
sp_qd_handle_on_invoice_upd_sts 10497 10497 0 0.00 0 27157 309 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 13168 53 0 0
sp_reserve_write_off 9943 9943 0 0.00 5 16422 168 0 0
sp_supplier_invoice 5412 5412 0 0.00 7 86811 3227 0 0
sp_supplier_order 5474 5474 0 0.00 5 35499 1292 0 0
srv_aggregate_perf_data 101 101 0 0.00 2786 177069 20662 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1558 173230 53201 0 0
srv_find_qd_qs_mism 1044477 1044477 0 0.00 0 18937 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 43 76295 1134 0 0
srv_make_money_saldo 801 801 0 0.00 0 901 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 88762 790764 336121 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 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-norm:sp_add_invoice_to_stock 4929 4929 0 0.00 0 12357 168 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 1 4238 80 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 1997 11 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 1816 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 8189 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 104 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 3865 27 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 21435 1602 0 0
t$perf-norm:sp_cancel_write_off 1038 1038 0 0.00 0 17298 33 0 0
t$perf-norm:sp_client_order 20330 20330 0 0.00 0 14165 14 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 4237 10 0 0
t$perf-norm:sp_pay_from_customer 7204 7204 0 0.00 0 5618 8 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 10857 9 0 0
t$perf-norm:sp_reserve_write_off 9943 9943 0 0.00 0 5522 8 0 0
t$perf-norm:sp_supplier_invoice 5412 5412 0 0.00 0 10484 23 0 0
t$perf-norm:sp_supplier_order 5474 5474 0 0.00 0 9329 16 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 696 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 202 4 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 1125 18 0 0
t$perf-norm:srv_make_money_saldo 801 801 0 0.00 0 2585 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10124 9830 294 2.90 0 11885 20 0 294
v_all_customers 37486 37486 0 0.00 0 15 1 0 0
v_all_suppliers 30274 30274 0 0.00 0 24 1 0 0
v_all_wares 40660 40660 0 0.00 0 104 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 775 7 0 0
v_cancel_client_order 3554 3554 0 0.00 0 2312 5 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 2220 10 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 2217 7 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2466 10 0 0
v_cancel_supplier_invoice 798 790 8 1.00 0 3134 20 0 8
v_cancel_supplier_order 792 792 0 0.00 0 12370 18 0 0
v_cancel_write_off 2076 2076 0 0.00 0 9217 25 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 2640 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 5328 20 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 2948 4 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 665 2 0 0
v_max_non_paid_realizn 3726 3726 0 0.00 0 2780 4 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 3984 5 0 0
v_min_id_clo_res 989 989 0 0.00 0 1541 6 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 2136 3 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 28393 22 0 0
v_min_non_paid_realizn 7204 7204 0 0.00 0 14055 23 0 0
v_our_firm 10130 10130 0 0.00 0 23 1 0 0
v_reserve_write_off 19886 19886 0 0.00 0 15705 8 0 0
x_lock_depdocs_on_canc_invoice 11396 11396 0 0.00 0 28098 121 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 4045 319 0 0
x_make_qstorno_1000_1200 5474 5474 0 0.00 1 35493 1210 0 0
x_make_qstorno_1200_2000 5412 5412 0 0.00 3 86619 3100 0 0
x_make_qstorno_2100_3300 461447 461447 0 0.00 0 132910 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-10-2021 22:39:05 to 13-10-2021 22:39:09.

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:07:55 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:08:56 33677 8192000000 9907101168 10221584384 987268688 10.755 100 86 172 0 1016228784 6274352 22685744
20:10:36 18184 8192000000 9964027856 10292416512 1045741424 11.320 100 90 180 0 1077306896 8605936 22959536
20:12:09 18207 8192000000 10006302992 10347360256 1092171808 11.763 100 81 162 0 1122068944 10185104 19712032
20:13:30 16181 8192000000 10052285232 10401886208 1133858240 12.158 100 84 168 3 1165251920 11748224 19645456
20:14:47 10829 8192000000 10096649408 10458439680 1178551168 12.577 100 79 158 3 1209185536 13315568 17318800
20:16:01 4897 8192000000 10125419104 10495430656 1216801744 12.932 100 73 146 1 1245055792 12114976 16139072
20:17:07 3264 8192000000 10167897040 10550272000 1261036672 13.340 100 69 135 0 1287061232 11091584 14932976
20:18:14 1969 8192000000 10212359680 10595921920 1290136304 13.605 100 85 170 0 1323870480 13374544 20359632
20:19:18 2230 8192000000 10225276336 10609172480 1305590320 13.746 100 92 184 4 1341233696 14287424 21355952
20:20:22 14450 8192000000 10211477888 10608578560 1307132304 13.760 100 59 118 0 1329204048 9116688 12955056
20:21:37 4815 8192000000 10217930304 10620977152 1319072032 13.868 100 51 103 0 1340063888 9870848 11121008
20:22:45 5698 8192000000 10215890720 10629521408 1322286432 13.897 100 35 70 0 1338460832 8207312 7967088
20:23:52 9514 8192000000 10219988160 10638864384 1330524704 13.972 100 29 58 2 1344033936 7316464 6192768
20:25:04 7759 8192000000 10225195744 10646958080 1337250960 14.033 100 29 58 0 1351013184 7024672 6737552
20:26:13 29552 8192000000 10227817248 10654638080 1341393296 14.070 100 56 113 0 1361972208 7177440 13401472
20:27:45 5163 8192000000 10239655792 10659385344 1342650784 14.081 100 35 70 1 1358918208 8216160 8051264
20:28:52 6597 8192000000 10211400112 10626535424 1301458848 13.709 98 23 46 0 1311797216 4830448 5507920
20:29:59 10742 8192000000 10162054128 10573185024 1230610272 13.060 95 20 41 1 1240604912 4923504 5071136
20:31:11 13504 8192000000 10161573008 10542088192 1194948960 12.729 94 70 139 2 1218229600 6194224 17086416
20:32:25 5032 8192000000 10165668576 10559668224 1264200304 13.369 100 48 95 2 1281298112 7363680 9734128
20:33:31 6070 8192000000 10162676224 10563719168 1249430816 13.233 98 21 42 0 1260310496 5801696 5077984
20:34:41 10523 8192000000 10160964864 10570305536 1254737088 13.282 98 9 18 0 1262380064 5814352 1828624
20:35:52 28175 8192000000 10189500864 10596974592 1290615296 13.610 100 38 77 2 1309263472 9975600 8672576
20:37:21 5720 8192000000 10210642032 10623774720 1317725072 13.856 100 21 41 1 1327550096 5210592 4614432
20:38:31 6392 8192000000 10247578272 10654183424 1340077840 14.058 100 46 92 2 1357769904 7390832 10301232
20:39:43 9243 8192000000 10262673984 10675716096 1350763152 14.154 100 52 104 1 1375867168 12889792 12214224
20:40:55 76209 8192000000 10291975616 10715615232 1375215152 14.374 100 45 90 0 1399988992 13736144 11037696
20:43:16 104342 8192000000 10308875888 10745311232 1387927936 14.487 100 23 46 1 1416665536 23056784 5680816
20:46:18 6698 8192000000 10333225680 10754564096 1406768224 14.655 100 62 125 0 1446675664 25116144 14791296
20:47:29 14371 8192000000 10344201536 10773458944 1418154032 14.756 100 48 94 1 1459500320 29653024 11693264
20:48:53 3395 8192000000 10353729456 10784485376 1424912144 14.816 100 45 90 2 1466949664 30633392 11404128
20:49:59 5994 8192000000 10369457536 10793426944 1426122208 14.827 100 71 144 2 1477890112 34435424 17332480
20:51:11 4752 8192000000 10383758192 10805444608 1434946800 14.905 100 79 157 1 1491332704 37408288 18977616
20:52:20 2039 8192000000 10385323456 10805686272 1431819856 14.877 100 82 164 1 1488892224 36860208 20212160
20:53:26 1558 8192000000 10366545072 10791743488 1424984080 14.817 100 75 151 1 1474349888 30749664 18616144
20:54:34 8387 8192000000 10362383648 10785533952 1415183344 14.730 100 73 146 2 1473800336 40300128 18316864
20:55:45 7392 8192000000 10336735872 10758287360 1388114624 14.489 99 52 105 1 1439619680 37593296 13911760
20:56:58 2792 8192000000 10377304448 10787024896 1404799024 14.638 100 83 165 1 1471737232 46936912 20001296
20:58:04 11380 8192000000 10353277424 10789007360 1414072864 14.720 100 41 80 0 1462912272 38313936 10525472
20:59:37 17426 8192000000 10333240976 10774867968 1394506240 14.546 100 28 54 1 1445369648 42972672 7890736
21:00:56 17209 8192000000 10322340320 10764963840 1383884336 14.451 100 33 67 3 1442278880 49281488 9113056
21:02:18 35579 8192000000 10275954368 10715119616 1332375408 13.989 97 23 46 1 1375242256 36740208 6126640
21:03:55 28519 8192000000 10272676128 10715729920 1331912544 13.984 99 15 30 0 1372640304 36371984 4355776
21:05:45 37307 8192000000 10266666416 10685206528 1288014048 13.586 95 49 99 0 1318434944 17650864 12770032
21:07:24 41104 8192000000 10270100896 10713935872 1328708896 13.955 98 11 22 1 1358857456 27447024 2701536
21:09:06 118577 8192000000 10267176096 10704650240 1326936560 13.939 98 36 72 0 1353860352 18315648 8608144
21:12:13 37876 8192000000 10250705104 10693697536 1313608288 13.819 97 20 40 0 1329522656 11606880 4307488
21:14:18 173230 8192000000 10251510032 10709889024 1315397776 13.835 97 11 22 0 1337503440 19652160 2453504
21:19:34 72492 8192000000 10271500272 10714329088 1333560528 13.999 98 15 31 1 1349573632 12877472 3135632
21:21:49 41555 8192000000 10278925264 10727673856 1371920096 14.344 100 25 50 0 1391370608 13576032 5874480
21:23:34 23774 8192000000 10285320624 10732974080 1377690448 14.396 100 40 80 1 1395457824 9165696 8601680
21:25:05 62940 8192000000 10299916896 10750967808 1387490576 14.483 100 17 34 0 1403319760 12264576 3564608
21:27:10 108579 8192000000 10282673264 10745286656 1359225920 14.230 98 8 16 0 1377092368 15900736 1965712
21:30:06 92715 8192000000 10298226208 10751668224 1374161808 14.364 99 16 30 0 1388188304 10524960 3501536
21:32:42 64141 8192000000 10307272960 10767151104 1399796768 14.593 100 30 58 2 1420416752 13693392 6926592
21:34:49 89934 8192000000 10311148688 10761478144 1400588592 14.600 100 26 52 0 1413944080 7766240 5589248
21:37:25 5325 8192000000 10308411808 10752126976 1369719968 14.325 98 45 89 1 1388366848 9058096 9588784
21:38:42 9824 8192000000 10306450352 10753974272 1390121904 14.507 100 49 98 1 1413932704 13682288 10128512
21:39:55 24349 8192000000 10289795824 10745544704 1376893648 14.389 99 21 41 1 1387860832 6601744 4365440
21:41:22 40981 8192000000 10275011104 10743734272 1356786144 14.208 98 8 16 0 1367777280 9369216 1621920
21:43:06 57725 8192000000 10289206272 10747637760 1385851728 14.469 100 22 44 0 1400241600 9138160 5251712
21:45:12 67625 8192000000 10257292432 10725269504 1290591920 13.610 93 18 36 1 1309497952 14301792 4604240
21:47:20 74749 8192000000 10260912416 10719465472 1321106112 13.887 96 22 44 0 1336660272 10077024 5477136
21:49:35 90257 8192000000 10279355568 10746216448 1345060304 14.103 97 22 44 1 1363855600 13342736 5452560
21:52:07 95020 8192000000 10282055392 10712444928 1257757312 13.309 91 24 48 1 1272956976 9814624 5385040
21:54:43 88180 8192000000 10262254224 10704424960 1230896736 13.062 90 20 40 1 1250798560 15218784 4683040
21:57:27 143854 8192000000 10248163440 10699251712 1321543264 13.891 98 23 46 0 1345552544 18890224 5119056
22:00:55 25679 8192000000 10257693760 10693242880 1335432976 14.016 99 47 92 2 1356516112 9946736 11136400
22:02:21 43601 8192000000 10247786608 10695843840 1344934640 14.102 100 9 18 0 1355193328 7712384 2546304
22:04:08 43814 8192000000 10238682768 10686144512 1323025904 13.904 98 12 23 0 1332056928 6302832 2728192
22:05:56 70404 8192000000 10257505952 10713198592 1356782096 14.208 100 19 38 1 1374593728 13196432 4615200
22:08:07 89878 8192000000 10258446384 10719252480 1315767056 13.838 96 9 18 1 1330422448 12967424 1687968
22:10:37 54430 8192000000 10270782864 10715975680 1319428768 13.872 96 18 37 0 1334668608 11358480 3881360
22:12:38 157980 8192000000 10289624624 10745896960 1356469968 14.206 98 11 22 0 1374650624 15578768 2601888
22:16:23 36322 8192000000 10309787952 10749624320 1387737776 14.486 100 49 98 0 1412223488 13448336 11037376
22:18:24 164160 8192000000 10314737792 10769637376 1337299664 14.033 96 11 22 0 1355790960 16402848 2088448
22:22:15 138709 8192000000 10298474336 10759892992 1318646496 13.864 94 16 32 0 1341856016 19770144 3439376
22:25:37 10969 8192000000 10299664128 10748002304 1376101264 14.382 99 38 76 0 1394711232 10110672 8499296
22:26:49 42909 8192000000 10279480480 10742358016 1381402592 14.429 100 13 26 1 1393594256 9415600 2776064
22:28:39 95683 8192000000 10304461424 10768900096 1381932336 14.434 99 11 22 1 1398017520 13722336 2362848
22:31:19 74345 8192000000 10291212624 10752831488 1380191920 14.418 99 15 30 0 1395517200 11754784 3570496
22:33:39 31032 8192000000 10296081136 10750414848 1382528112 14.439 99 27 54 1 1395723456 6796576 6398768
22:35:10 42315 8192000000 10266215552 10716037120 1299615088 13.692 94 23 46 1 1315185104 10255312 5314704
22:37:15 43946 8192000000 9191646144 9448579072 343943424 4.029 25 5 10 0 349305888 4158400 1204064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-10-2021 22:39:11 to 13-10-2021 22:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 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 13-10-2021 22:39:26 to 13-10-2021 22:39:28.

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          235884
MON$OLDEST_ACTIVE               235885
MON$OLDEST_SNAPSHOT             235885
MON$NEXT_TRANSACTION            235885
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                       2017920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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'

13.10.21 22:39:29. 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
2021-10-13 22:39:31 No severe PSQL-related problems occured
13.10.21 22:39:33. End of report.