Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.08.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 16339476 kB
MemAvailable 30873512 kB
Buffers 0 kB
Cached 15308128 kB
SwapCached 324 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-08-30 22:37:45
DTS_END                         2021-08-30 22:37:45.5180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46066
TRN_ID                          241255
ATT_ID                          655
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: 9170.61 26975 1100473
customer order: creation 175.62 326 21074
customer order: refuse 14.65 330 1758
order to supplier: creation 46.82 1179 5618
order to supplier: removal 3.48 83405 417
invoice (draft): creation 47.40 3241 5688
invoice (draft): removal 50.58 684 6070
invoice accept: apply 42.98 16977 5158
invoice accept: cancel 45.90 816 5508
customer reserve: creation 3951.95 168 474234
customer reserve: removal 4484.64 50 538157
realization accept: apply 84.36 178 10123
realization accept: cancel 91.23 109 10948
payment from customer: creation 60.01 125 7201
payment from customer: removal 5.70 78 684
payment to supplier: creation 49.65 119 5958
payment to supplier: removal 3.73 82 447
service: total inventory turnovers 4.80 1227 576
service: total monetary turnovers 6.30 29 756
service: aggregate perf. data 0.78 21705 94
service: refresh index statistics 0.03 408677 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-08-2021 22:39:00 to 30-08-2021 22:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1741
TEST_TIME 2 1925
TEST_TIME 3 3864
TEST_TIME 4 3506
TEST_TIME 5 3710
TEST_TIME 6 4039
TEST_TIME 7 4639
TEST_TIME 8 4862
TEST_TIME 9 5087
TEST_TIME 10 5444
TEST_TIME 11 6290
TEST_TIME 12 6272
TEST_TIME 13 6741
TEST_TIME 14 7039
TEST_TIME 15 7194
TEST_TIME 16 7522
TEST_TIME 17 7945
TEST_TIME 18 8116
TEST_TIME 19 8332
TEST_TIME 20 8956
TEST_TIME 21 9102
TEST_TIME 22 9551
TEST_TIME 23 10143
TEST_TIME 24 10501
TEST_TIME 25 10547
TEST_TIME 26 10353
TEST_TIME 27 10186
TEST_TIME 28 9903
TEST_TIME 29 9678
TEST_TIME 30 10137
TEST_TIME 31 10192
TEST_TIME 32 10004
TEST_TIME 33 9982
TEST_TIME 34 9746
TEST_TIME 35 9769
TEST_TIME 36 9695
TEST_TIME 37 9730
TEST_TIME 38 9647
TEST_TIME 39 9625
TEST_TIME 40 9555
TEST_TIME 41 9580
TEST_TIME 42 9598
TEST_TIME 43 9506
TEST_TIME 44 9531
TEST_TIME 45 9603
TEST_TIME 46 9524
TEST_TIME 47 9446
TEST_TIME 48 9606
TEST_TIME 49 9721
TEST_TIME 50 9717
TEST_TIME 51 9670
TEST_TIME 52 9660
TEST_TIME 53 9683
TEST_TIME 54 9702
TEST_TIME 55 9535
TEST_TIME 56 9499
TEST_TIME 57 9484
TEST_TIME 58 9404
TEST_TIME 59 9371
TEST_TIME 60 9440
TEST_TIME 61 9412
TEST_TIME 62 9361
TEST_TIME 63 9276
TEST_TIME 64 9312
TEST_TIME 65 9285
TEST_TIME 66 9333
TEST_TIME 67 9293
TEST_TIME 68 9295
TEST_TIME 69 9429
TEST_TIME 70 9472
TEST_TIME 71 9407
TEST_TIME 72 9431
TEST_TIME 73 9426
TEST_TIME 74 9415
TEST_TIME 75 9469
TEST_TIME 76 9508
TEST_TIME 77 9503
TEST_TIME 78 9509
TEST_TIME 79 9580
TEST_TIME 80 9475
TEST_TIME 81 9419
TEST_TIME 82 9348
TEST_TIME 83 9323
TEST_TIME 84 9341
TEST_TIME 85 9282
TEST_TIME 86 9264
TEST_TIME 87 9255
TEST_TIME 88 9180
TEST_TIME 89 9132
TEST_TIME 90 9071
TEST_TIME 91 9113
TEST_TIME 92 9108
TEST_TIME 93 9129
TEST_TIME 94 9111
TEST_TIME 95 9094
TEST_TIME 96 9206
TEST_TIME 97 9225
TEST_TIME 98 9225
TEST_TIME 99 9219
TEST_TIME 100 9305
TEST_TIME 101 9291
TEST_TIME 102 9288
TEST_TIME 103 9373
TEST_TIME 104 9381
TEST_TIME 105 9396
TEST_TIME 106 9343
TEST_TIME 107 9310
TEST_TIME 108 9280
TEST_TIME 109 9228
TEST_TIME 110 9188
TEST_TIME 111 9165
TEST_TIME 112 9100
TEST_TIME 113 9154
TEST_TIME 114 9136
TEST_TIME 115 9120
TEST_TIME 116 9102
TEST_TIME 117 9163
TEST_TIME 118 9136
TEST_TIME 119 9102
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-08-2021 22:39:04 to 30-08-2021 22:39:07.

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 1538585 1538585 0 0.00 0 17664 6 0 0
doc_list_biud 1538585 1538585 0 0.00 0 60290 23 0 0
sp_add_invoice_to_stock 5247 5158 89 1.69 33 191898 16977 0 89
sp_cancel_adding_invoice 5508 5508 0 0.00 5 76386 817 0 0
sp_cancel_client_order 1758 1758 0 0.00 4 7285 331 0 0
sp_cancel_customer_reserve 538157 538157 0 0.00 1 34548 51 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 3 8789 78 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 3 4694 82 0 0
sp_cancel_supplier_invoice 6075 6070 5 0.08 2 41375 684 0 5
sp_cancel_supplier_order 417 417 0 0.00 6 1096073 83405 0 0
sp_cancel_write_off 10948 10948 0 0.00 1 10563 110 0 0
sp_client_order 21074 21074 0 0.00 3 28140 326 0 0
sp_customer_reserve 474234 474234 0 0.00 0 115781 168 0 0
sp_fill_shopping_cart 32380 32380 0 0.00 1 17309 39 0 0
sp_fill_shopping_cart_clo_res 474230 474230 0 0.00 0 11677 13 0 0
sp_get_clo_for_invoice 5152 5152 0 0.00 0 16463 306 0 0
sp_kill_cost_storno 17587 17587 0 0.00 0 5784 54 0 0
sp_kill_qstorno_ret_qs2qd 1093749 1093749 0 0.00 0 41299 28 0 0
sp_kill_qty_storno 578133 578133 0 0.00 0 60289 61 0 0
sp_lock_selected_doc 21139 21139 0 0.00 0 8767 6 0 0
sp_make_cost_storno 28434 28434 0 0.00 0 11052 48 0 0
sp_multiply_rows_for_pdistr 28434 28434 0 0.00 0 7516 32 0 0
sp_multiply_rows_for_qdistr 21074 21074 0 0.00 0 27333 169 0 0
sp_pay_from_customer 7201 7201 0 0.00 4 29585 125 0 0
sp_pay_to_supplier 5958 5958 0 0.00 4 15973 120 0 0
sp_payment_common 13159 13159 0 0.00 1 24971 33 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 7277 297 0 0
sp_qd_handle_on_invoice_upd_sts 10660 10660 0 0.00 0 60288 336 0 0
sp_qd_handle_on_reserve_upd_sts 10123 10123 0 0.00 0 33541 60 0 0
sp_reserve_write_off 10123 10123 0 0.00 7 33588 179 0 0
sp_supplier_invoice 5688 5688 0 0.00 6 104296 3241 0 0
sp_supplier_order 5618 5618 0 0.00 5 63335 1179 0 0
srv_aggregate_perf_data 94 94 0 0.00 3015 209496 21705 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2795 158764 48082 0 0
srv_find_qd_qs_mism 1051215 1051215 0 0.00 0 17661 5 0 0
srv_make_invnt_saldo 576 576 0 0.00 18 113767 1227 0 0
srv_make_money_saldo 756 756 0 0.00 1 4570 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 251230 616107 408678 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5158 5158 0 0.00 0 22055 169 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 5856 124 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 12150 24 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 9473 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 1671 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 304 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 4435 21 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 19183 1366 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 781 4 0 0
t$perf-norm:sp_client_order 21074 21074 0 0.00 0 9509 17 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 11492 23 0 0
t$perf-norm:sp_pay_from_customer 7201 7201 0 0.00 0 15211 14 0 0
t$perf-norm:sp_pay_to_supplier 5958 5958 0 0.00 0 5919 12 0 0
t$perf-norm:sp_reserve_write_off 10123 10123 0 0.00 0 9470 9 0 0
t$perf-norm:sp_supplier_invoice 5688 5688 0 0.00 0 13544 19 0 0
t$perf-norm:sp_supplier_order 5618 5618 0 0.00 0 9182 18 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 498 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 103 4 0 0
t$perf-norm:srv_make_invnt_saldo 576 576 0 0.00 0 3719 29 0 0
t$perf-norm:srv_make_money_saldo 756 756 0 0.00 0 605 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 36 10 0 0
v_add_invoice_to_stock 10482 10304 178 1.69 0 6070 17 0 178
v_all_customers 38268 38268 0 0.00 0 16 1 0 0
v_all_suppliers 30964 30964 0 0.00 0 22 1 0 0
v_all_wares 42148 42148 0 0.00 0 32 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 61 6 0 0
v_cancel_client_order 3516 3516 0 0.00 0 4872 9 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 2729 8 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 865 4 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 3818 7 0 0
v_cancel_supplier_invoice 816 806 10 1.22 0 3426 19 0 10
v_cancel_supplier_order 834 834 0 0.00 0 5097 13 0 0
v_cancel_write_off 2040 2040 0 0.00 0 5015 17 0 0
v_max_id_clo_ord 5618 5618 0 0.00 0 326 1 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 2222 17 0 0
v_max_id_ord_sup 5688 5688 0 0.00 0 4501 4 0 0
v_max_non_paid_invoice 1782 1782 0 0.00 0 937 3 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 2767 5 0 0
v_min_id_clo_ord 5618 5618 0 0.00 0 2174 5 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 2982 8 0 0
v_min_id_ord_sup 5688 5688 0 0.00 0 8292 13 0 0
v_min_non_paid_invoice 5958 5958 0 0.00 0 8190 15 0 0
v_min_non_paid_realizn 7201 7201 0 0.00 0 7437 20 0 0
v_our_firm 10626 10626 0 0.00 0 14 1 0 0
v_reserve_write_off 20246 20246 0 0.00 0 5307 7 0 0
x_lock_depdocs_on_canc_invoice 11175 11175 0 0.00 0 12788 109 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 8518 314 0 0
x_make_qstorno_1000_1200 5618 5618 0 0.00 2 63327 1104 0 0
x_make_qstorno_1200_2000 5688 5688 0 0.00 3 101502 3096 0 0
x_make_qstorno_2100_3300 474234 474234 0 0.00 0 115779 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-08-2021 22:39:08 to 30-08-2021 22:39:12.

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:49 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:49 31059 8192000000 10374041824 10690981888 1413419472 14.714 100 91 182 0 1448797696 6191872 29186352
20:10:26 16736 8192000000 10425658912 10756784128 1482010672 15.319 100 81 163 0 1516665472 8577408 26077392
20:11:49 18424 8192000000 10476495168 10818064384 1530573856 15.742 100 80 160 0 1565415216 9951024 24890336
20:13:13 7681 8192000000 10567446240 10923896832 1626869584 16.568 100 83 166 1 1663738160 11939360 24929216
20:14:25 10303 8192000000 10609973120 10975481856 1666987264 16.908 100 81 161 2 1702955488 12080576 23887648
20:15:40 5347 8192000000 10673021136 11046690816 1736649776 17.491 100 82 165 0 1772597968 12949552 22998640
20:16:49 3302 8192000000 10739837408 11124727808 1802352320 18.033 100 88 175 1 1839198272 11910656 24935296
20:17:53 2016 8192000000 10783440128 11170832384 1839826800 18.339 100 78 155 3 1874574160 12835024 21912336
20:18:57 1696 8192000000 10819604448 11209916416 1876818880 18.639 100 92 184 2 1914748256 11934576 25994800
20:19:59 3052 8192000000 10811736672 11223101440 1885945472 18.713 100 54 110 1 1911805728 11471552 14388704
20:21:03 20239 8192000000 10837126240 11239337984 1896093872 18.795 100 68 139 2 1923676256 7925584 19656800
20:22:24 9388 8192000000 10811130816 11235905536 1898706656 18.816 100 28 55 2 1912662864 5748768 8207440
20:23:35 11183 8192000000 10806922992 11245719552 1902650368 18.848 100 19 37 3 1914073520 6024464 5398688
20:24:48 10934 8192000000 10800431408 11238400000 1885265520 18.708 99 16 32 0 1895761504 6188400 4307584
20:26:04 11153 8192000000 10808422512 11249799168 1904082496 18.859 100 14 28 1 1913933040 5982464 3868080
20:27:18 39683 8192000000 10793227840 11231166464 1870285376 18.587 99 21 42 1 1881953648 5958096 5710176
20:29:01 5194 8192000000 10749264816 11169955840 1820322048 18.180 98 35 70 1 1836872496 5943120 10607328
20:30:07 11590 8192000000 10724480944 11153530880 1786027520 17.899 98 17 34 0 1796401344 5696512 4677312
20:31:19 25193 8192000000 10715115088 11144769536 1772044512 17.784 97 30 58 0 1785732048 5424448 8263088
20:33:10 7345 8192000000 10721220544 11142033408 1808866240 18.087 100 41 80 3 1828253136 7989664 11397232
20:34:18 5617 8192000000 10750526720 11168280576 1824789312 18.217 100 23 46 0 1838066736 5919408 7358016
20:35:24 9858 8192000000 10755798400 11184271360 1845814768 18.388 100 13 26 1 1854856192 5124448 3916976
20:36:35 65765 8192000000 10801807968 11229970432 1878188320 18.650 100 34 68 2 1893342944 5137520 10017104
20:38:48 38302 8192000000 10845782336 11266535424 1913280784 18.933 100 41 82 1 1935144240 10189536 11673920
20:40:29 58424 8192000000 10867166096 11301347328 1930151120 19.068 100 25 50 0 1950154560 12505792 7497648
20:42:34 21166 8192000000 10884794816 11321077760 1950810672 19.233 100 46 90 3 1984911712 20178224 13922816
20:44:07 7811 8192000000 10913728000 11345010688 1963385616 19.333 100 47 94 2 2000445824 23548016 13512192
20:45:21 4946 8192000000 10921673664 11349868544 1958924160 19.297 99 54 109 2 1999503360 25015312 15563888
20:46:34 7469 8192000000 10954695824 11376316416 1991428288 19.555 100 83 164 1 2043850896 28209376 24213232
20:47:43 6100 8192000000 10965656000 11384381440 1994495408 19.579 100 70 139 1 2043801376 29253440 20052528
20:48:55 11715 8192000000 10969012256 11392495616 1981699376 19.478 99 79 160 1 2038102864 32846576 23556912
20:50:17 5989 8192000000 10968922624 11398172672 1991470000 19.555 100 70 136 1 2057280864 44877024 20933840
20:51:27 2831 8192000000 10975451280 11394445312 1996184912 19.593 100 81 162 0 2056833968 36261504 24387552
20:52:35 3015 8192000000 10973656416 11389779968 1985639952 19.509 100 84 167 0 2051946240 41300720 25005568
20:53:43 2794 8192000000 10984192416 11397758976 1965974240 19.353 98 87 174 0 2038256688 45475360 26807088
20:54:52 10037 8192000000 10956918080 11394080768 1984843696 19.503 100 50 100 1 2036746864 36065136 15838032
20:56:04 9580 8192000000 10935093616 11372953600 1963743552 19.336 99 47 94 4 2018905536 39206048 15955936
20:57:20 4549 8192000000 10887101024 11325763584 1864278864 18.538 95 56 113 1 1924106704 42818048 17009792
20:58:27 15866 8192000000 10874718256 11323375616 1870173104 18.586 97 26 52 1 1918962192 39586096 9202992
20:59:51 34648 8192000000 10870658320 11332792320 1908890080 18.898 99 26 52 0 1952083968 34634704 8559184
21:01:34 63416 8192000000 10847852368 11301502976 1795516128 17.977 93 25 50 2 1822988608 19641568 7830912
21:04:07 50893 8192000000 10876659696 11317342208 1897965600 18.810 98 33 65 2 1923094800 16117312 9011888
21:06:04 158764 8192000000 10867355824 11342065664 1877298336 18.643 97 19 38 1 1910906656 28144368 5463952
21:09:43 34368 8192000000 10876938448 11332468736 1926830752 19.042 98 39 77 0 1946237360 8946640 10459968
21:11:19 20198 8192000000 10864065296 11321917440 1935106592 19.108 99 11 22 0 1945430800 7413632 2910576
21:12:41 52274 8192000000 10901326240 11361746944 1957882064 19.289 99 27 52 2 1979823712 14201728 7739920
21:14:36 21981 8192000000 10900384528 11372093440 1968664256 19.375 100 12 24 1 1991541072 19773792 3103024
21:16:31 28117 8192000000 10891965008 11357130752 1958164064 19.291 99 16 32 0 1977136752 14607888 4364800
21:18:04 55619 8192000000 10904116496 11347509248 1924188832 19.020 98 11 22 0 1934853360 7571504 3093024
21:20:01 25191 8192000000 10901012960 11354202112 1950269024 19.229 99 16 32 0 1962056768 7650368 4137376
21:21:40 59087 8192000000 10897620768 11369504768 1965969024 19.353 100 16 32 1 1984359760 13509584 4881152
21:23:48 81710 8192000000 10917568928 11398795264 1983367760 19.491 100 7 14 0 2003980176 18527744 2084672
21:26:16 84143 8192000000 10910510240 11384303616 1923926176 19.018 97 14 29 1 1945221936 17341968 3953792
21:28:40 47987 8192000000 10899629984 11347144704 1917278144 18.965 97 31 62 0 1934409392 8298816 8832432
21:30:29 151243 8192000000 10907826464 11373641728 1922555648 19.007 97 15 30 0 1941450416 14276976 4617792
21:34:02 66004 8192000000 10917731248 11390263296 1971396960 19.397 99 21 42 1 1994137504 16544608 6195936
21:36:35 50993 8192000000 10918660256 11404861440 1999788448 19.621 100 33 66 5 2027051008 18107104 9155456
21:39:01 21888 8192000000 10880703328 11350958080 1939204064 19.140 98 15 30 2 1959638768 16000016 4434688
21:42:25 77286 8192000000 10860902176 11326701568 1835508336 18.304 93 6 12 1 1846735728 9910944 1316448
21:44:44 23427 8192000000 10827632656 11274481664 1851187504 18.432 95 25 50 0 1867527568 9557568 6782496
21:46:11 78390 8192000000 10840530592 11310551040 1850639568 18.427 95 15 29 1 1867145152 12300416 4205168
21:48:39 40816 8192000000 10891060560 11333677056 1912397184 18.926 98 51 101 1 1939281696 12664208 14220304
21:50:22 17317 8192000000 10879658928 11324063744 1909915216 18.906 98 41 82 0 1931628272 10301536 11411520
21:51:41 43473 8192000000 10901125104 11360362496 1937505920 19.127 99 29 58 2 1958966928 13315888 8145120
21:53:45 80169 8192000000 10881126528 11332161536 1818836048 18.168 93 11 22 1 1837651760 16040352 2775360
21:56:07 149196 8192000000 10870671328 11334742016 1890651600 18.751 97 14 28 2 1907859904 13394448 3813856
21:59:46 70385 8192000000 10878015824 11352051712 1956726768 19.280 100 23 46 2 1974944192 11602432 6614992
22:02:07 41427 8192000000 10856394496 11322200064 1897374112 18.805 97 5 10 0 1905972960 7348864 1249984
22:04:00 63124 8192000000 10870390656 11343310848 1922305520 19.005 98 15 30 1 1934430880 6988752 5136608
22:06:03 35004 8192000000 10878275360 11345707008 1948491680 19.214 99 13 26 0 1963829648 11551696 3786272
22:07:39 71437 8192000000 10891724656 11365883904 1955667792 19.272 99 7 13 1 1969455216 11793232 1994192
22:09:55 51344 8192000000 10906173632 11379212288 1946763056 19.201 98 23 46 1 1961972256 9070784 6138416
22:11:47 50817 8192000000 10913627200 11381776384 1944731584 19.184 98 12 24 1 1966680608 18512656 3436368
22:14:08 37733 8192000000 10908422800 11362783232 1917955488 18.970 97 28 56 2 1935967008 10546704 7464816
22:15:46 45268 8192000000 10904916480 11360157696 1940871104 19.154 99 37 74 1 1968292512 17184352 10237056
22:17:42 73349 8192000000 10915769232 11381743616 1958349936 19.293 99 20 40 2 1984318416 20278192 5690288
22:20:01 63523 8192000000 10919765728 11395043328 1939529072 19.143 98 21 42 1 1960040816 14116928 6394816
22:22:06 126698 8192000000 10866384736 11329331200 1838927040 18.332 93 20 40 1 1861095648 16865984 5302624
22:25:23 64035 8192000000 10842038672 11320705024 1902766512 18.849 98 5 10 0 1911033904 7040336 1227056
22:27:30 45618 8192000000 10862837184 11337076736 1926248240 19.037 99 13 27 1 1938856512 9377280 3230992
22:29:31 91873 8192000000 10857891232 11337707520 1905140176 18.868 98 8 16 0 1923221104 15803872 2277056
22:32:06 48901 8192000000 10859531776 11320852480 1871627552 18.597 96 19 36 2 1886481360 9192048 5661760
22:34:06 56705 8192000000 10867082992 11328221184 1887448256 18.725 97 28 56 1 1913698704 17809360 8441088
22:36:14 66480 8192000000 10885247792 11364315136 1936928592 19.122 99 14 28 1 1962874064 21350288 4595184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-08-2021 22:39:14 to 30-08-2021 22:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-08-2021 22:39:29 to 30-08-2021 22:39:31.

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          240010
MON$OLDEST_ACTIVE               241406
MON$OLDEST_SNAPSHOT             241406
MON$NEXT_TRANSACTION            241406
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                       2008512
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:780: 
:781: mscs-test-161.budzdorov.ru    Mon Aug 30 22:37:55 2021
:782:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:783: 
:784: 
:785: mscs-test-161.budzdorov.ru    Mon Aug 30 22:37:59 2021
:786:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:787: 
:788: 
:789: mscs-test-161.budzdorov.ru    Mon Aug 30 22:38:05 2021
:790:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:791: 
:792: 
:793: mscs-test-161.budzdorov.ru    Mon Aug 30 22:38:06 2021
:794:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:795: 
--- end of diff output ---

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

30.08.21 22:39:32. 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-08-30 22:39:35 No severe PSQL-related problems occured
30.08.21 22:39:36. End of report.