Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.03.2022 04: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 16391340 kB
MemAvailable 31620196 kB
Buffers 20 kB
Cached 15288400 kB
SwapCached 2144 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-03-27 04:37:38
DTS_END                         2022-03-27 04:37:38.6870
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51512
TRN_ID                          215148
ATT_ID                          600
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: 8664.06 24961 1039687
customer order: creation 160.58 465 19270
customer order: refuse 13.82 373 1658
order to supplier: creation 43.51 1359 5221
order to supplier: removal 3.14 163128 377
invoice (draft): creation 44.01 3367 5281
invoice (draft): removal 49.03 963 5883
invoice accept: apply 39.58 28367 4749
invoice accept: cancel 44.03 1283 5283
customer reserve: creation 3667.93 272 440151
customer reserve: removal 4320.62 94 518474
realization accept: apply 78.83 288 9460
realization accept: cancel 75.59 160 9071
payment from customer: creation 56.48 165 6777
payment from customer: removal 5.56 134 667
payment to supplier: creation 46.54 193 5585
payment to supplier: removal 3.30 122 396
service: total inventory turnovers 3.65 4237 438
service: total monetary turnovers 6.99 388 839
service: aggregate perf. data 0.85 18148 102
service: refresh index statistics 0.04 275704 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-03-2022 04:39:50 to 27-03-2022 04:39:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6793
TEST_TIME 2 7075
TEST_TIME 3 5884
TEST_TIME 4 6010
TEST_TIME 5 6183
TEST_TIME 6 6890
TEST_TIME 7 6776
TEST_TIME 8 6991
TEST_TIME 9 6830
TEST_TIME 10 7097
TEST_TIME 11 7203
TEST_TIME 12 7251
TEST_TIME 13 7306
TEST_TIME 14 7406
TEST_TIME 15 7722
TEST_TIME 16 7700
TEST_TIME 17 7669
TEST_TIME 18 7609
TEST_TIME 19 7759
TEST_TIME 20 7688
TEST_TIME 21 8139
TEST_TIME 22 8518
TEST_TIME 23 8452
TEST_TIME 24 8569
TEST_TIME 25 8508
TEST_TIME 26 8593
TEST_TIME 27 8905
TEST_TIME 28 9426
TEST_TIME 29 9630
TEST_TIME 30 9883
TEST_TIME 31 10085
TEST_TIME 32 10253
TEST_TIME 33 10471
TEST_TIME 34 11059
TEST_TIME 35 11012
TEST_TIME 36 10805
TEST_TIME 37 10519
TEST_TIME 38 10359
TEST_TIME 39 10329
TEST_TIME 40 10227
TEST_TIME 41 10211
TEST_TIME 42 10150
TEST_TIME 43 10097
TEST_TIME 44 9973
TEST_TIME 45 9835
TEST_TIME 46 9770
TEST_TIME 47 9688
TEST_TIME 48 9617
TEST_TIME 49 9457
TEST_TIME 50 9344
TEST_TIME 51 9273
TEST_TIME 52 9204
TEST_TIME 53 9123
TEST_TIME 54 9063
TEST_TIME 55 9042
TEST_TIME 56 9054
TEST_TIME 57 9026
TEST_TIME 58 9006
TEST_TIME 59 9024
TEST_TIME 60 9081
TEST_TIME 61 9021
TEST_TIME 62 8989
TEST_TIME 63 9013
TEST_TIME 64 9154
TEST_TIME 65 9150
TEST_TIME 66 9197
TEST_TIME 67 9198
TEST_TIME 68 9218
TEST_TIME 69 9169
TEST_TIME 70 9202
TEST_TIME 71 9108
TEST_TIME 72 9105
TEST_TIME 73 9089
TEST_TIME 74 9110
TEST_TIME 75 9005
TEST_TIME 76 8995
TEST_TIME 77 8984
TEST_TIME 78 8896
TEST_TIME 79 8885
TEST_TIME 80 8896
TEST_TIME 81 8959
TEST_TIME 82 8931
TEST_TIME 83 8920
TEST_TIME 84 8982
TEST_TIME 85 8944
TEST_TIME 86 8972
TEST_TIME 87 8947
TEST_TIME 88 8979
TEST_TIME 89 8977
TEST_TIME 90 8971
TEST_TIME 91 9050
TEST_TIME 92 9026
TEST_TIME 93 8951
TEST_TIME 94 8932
TEST_TIME 95 8931
TEST_TIME 96 8877
TEST_TIME 97 8814
TEST_TIME 98 8762
TEST_TIME 99 8725
TEST_TIME 100 8724
TEST_TIME 101 8754
TEST_TIME 102 8747
TEST_TIME 103 8757
TEST_TIME 104 8724
TEST_TIME 105 8726
TEST_TIME 106 8770
TEST_TIME 107 8835
TEST_TIME 108 8793
TEST_TIME 109 8812
TEST_TIME 110 8828
TEST_TIME 111 8850
TEST_TIME 112 8869
TEST_TIME 113 8864
TEST_TIME 114 8830
TEST_TIME 115 8765
TEST_TIME 116 8793
TEST_TIME 117 8749
TEST_TIME 118 8755
TEST_TIME 119 8705
TEST_TIME 120 8687

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-03-2022 04:39:53 to 27-03-2022 04:39:57.

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 1444512 1444512 0 0.00 0 28380 14 0 0
doc_list_biud 1444500 1444500 0 0.00 0 68908 39 0 0
sp_add_invoice_to_stock 4860 4749 111 2.28 29 263614 28368 0 111
sp_cancel_adding_invoice 5283 5283 0 0.00 7 92588 1283 0 0
sp_cancel_client_order 1658 1658 0 0.00 5 5889 374 0 0
sp_cancel_customer_reserve 518474 518474 0 0.00 1 68916 95 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 4 4826 135 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 1619 123 0 0
sp_cancel_supplier_invoice 5895 5883 12 0.20 4 28860 964 0 12
sp_cancel_supplier_order 377 377 0 0.00 17 1426420 163129 0 0
sp_cancel_write_off 9071 9071 0 0.00 2 10883 160 0 0
sp_client_order 19270 19270 0 0.00 6 35377 465 0 0
sp_customer_reserve 440151 440151 0 0.00 1 90259 273 0 0
sp_fill_shopping_cart 29758 29758 0 0.00 1 14092 84 0 0
sp_fill_shopping_cart_clo_res 440118 440118 0 0.00 0 25334 19 0 0
sp_get_clo_for_invoice 4706 4706 0 0.00 0 25721 282 0 0
sp_kill_cost_storno 15417 15417 0 0.00 0 10878 69 0 0
sp_kill_qstorno_ret_qs2qd 1052279 1052279 0 0.00 0 65631 41 0 0
sp_kill_qty_storno 554909 554909 0 0.00 0 68906 95 0 0
sp_lock_selected_doc 19646 19646 0 0.00 0 9588 7 0 0
sp_make_cost_storno 26527 26527 0 0.00 0 23226 83 0 0
sp_multiply_rows_for_pdistr 26525 26525 0 0.00 0 7212 36 0 0
sp_multiply_rows_for_qdistr 19270 19270 0 0.00 0 11718 153 0 0
sp_pay_from_customer 6777 6777 0 0.00 5 17880 165 0 0
sp_pay_to_supplier 5585 5585 0 0.00 6 24656 194 0 0
sp_payment_common 12362 12362 0 0.00 2 7316 47 0 0
sp_qd_handle_on_cancel_clo 1658 1658 0 0.00 0 5855 307 0 0
sp_qd_handle_on_invoice_upd_sts 9987 9987 0 0.00 1 42987 505 0 0
sp_qd_handle_on_reserve_upd_sts 9459 9459 0 0.00 0 24116 68 0 0
sp_reserve_write_off 9460 9460 0 0.00 9 28410 289 0 0
sp_supplier_invoice 5281 5281 0 0.00 12 109180 3368 0 0
sp_supplier_order 5221 5221 0 0.00 7 47306 1359 0 0
srv_aggregate_perf_data 102 102 0 0.00 2682 172506 18148 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 711 142465 39914 0 0
srv_find_qd_qs_mism 993009 993009 0 0.00 0 22357 12 0 0
srv_make_invnt_saldo 438 438 0 0.00 69 58419 4238 0 0
srv_make_money_saldo 839 839 0 0.00 1 10746 388 0 0
srv_recalc_idx_stat 5 5 0 0.00 120345 424068 275704 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4751 4751 0 0.00 0 24112 867 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 1 9504 548 0 0
t$perf-norm:sp_cancel_client_order 1658 1658 0 0.00 0 3248 29 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2570 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 849 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 2711 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 797 35 0 0
t$perf-norm:sp_cancel_supplier_order 377 377 0 0.00 0 93739 6651 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 3093 22 0 0
t$perf-norm:sp_client_order 19270 19270 0 0.00 0 13180 37 0 0
t$perf-norm:sp_customer_reserve 931 931 0 0.00 0 6758 28 0 0
t$perf-norm:sp_pay_from_customer 6777 6777 0 0.00 0 7945 21 0 0
t$perf-norm:sp_pay_to_supplier 5585 5585 0 0.00 0 9935 20 0 0
t$perf-norm:sp_reserve_write_off 9460 9460 0 0.00 0 24094 28 0 0
t$perf-norm:sp_supplier_invoice 5281 5281 0 0.00 0 4011 30 0 0
t$perf-norm:sp_supplier_order 5221 5221 0 0.00 0 4848 32 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 574 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 290 11 0 0
t$perf-norm:srv_make_invnt_saldo 438 438 0 0.00 0 20873 64 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1039 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 224 95 0 0
v_add_invoice_to_stock 9624 9402 222 2.30 0 12094 26 0 222
v_all_customers 35642 35642 0 0.00 0 236 4 0 0
v_all_suppliers 29344 29344 0 0.00 0 107 4 0 0
v_all_wares 38540 38540 0 0.00 0 143 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1396 15 0 0
v_cancel_client_order 3316 3316 0 0.00 0 1165 8 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2047 11 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 1 891 10 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 1214 12 0 0
v_cancel_supplier_invoice 780 756 24 3.07 0 1002 25 0 24
v_cancel_supplier_order 754 754 0 0.00 0 547 10 0 0
v_cancel_write_off 1880 1880 0 0.00 0 4379 27 0 0
v_max_id_clo_ord 5221 5221 0 0.00 0 2390 6 0 0
v_max_id_clo_res 931 931 0 0.00 0 2264 12 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 6706 10 0 0
v_max_non_paid_invoice 1401 1401 0 0.00 0 4182 10 0 0
v_max_non_paid_realizn 3492 3492 0 0.00 0 3970 9 0 0
v_min_id_clo_ord 5221 5221 0 0.00 0 1659 9 0 0
v_min_id_clo_res 931 931 0 0.00 0 3179 15 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 3313 8 0 0
v_min_non_paid_invoice 5585 5585 0 0.00 0 5847 18 0 0
v_min_non_paid_realizn 6777 6777 0 0.00 0 5368 18 0 0
v_our_firm 9468 9468 0 0.00 0 87 4 0 0
v_reserve_write_off 18918 18918 0 0.00 0 3106 10 0 0
x_lock_depdocs_on_canc_invoice 10788 10788 0 0.00 0 39060 130 0 0
x_lock_depdocs_on_canc_sup_ord 377 377 0 0.00 0 8586 392 0 0
x_make_qstorno_1000_1200 5221 5221 0 0.00 2 47296 1179 0 0
x_make_qstorno_1200_2000 5281 5281 0 0.00 4 100780 3149 0 0
x_make_qstorno_2100_3300 440149 440149 0 0.00 0 90248 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-03-2022 04:39:58 to 27-03-2022 04:40:02.

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
02:07:41 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:42 31351 8192000000 9718819984 10020126720 797720448 8.873 100 89 178 0 826852000 7630560 21500992
02:10:18 16922 8192000000 9761973088 10070958080 833860096 9.238 100 83 166 0 866237328 12333008 20044224
02:11:39 20172 8192000000 9801531744 10122473472 864788208 9.548 100 80 160 0 905763824 22447552 18528064
02:13:11 10538 8192000000 9876296336 10210992128 915971184 10.056 100 78 155 2 982330048 49656640 16702224
02:14:27 4814 8192000000 9925391168 10271014912 966910752 10.557 100 91 179 3 1042384512 56602960 18870800
02:15:35 1966 8192000000 9977196400 10335993856 1003922592 10.917 100 90 172 0 1090543232 69049104 17571536
02:16:39 994 8192000000 10000857744 10367983616 1025130224 11.122 100 93 172 5 1117328464 73566960 18631280
02:17:42 971 8192000000 10016729120 10388860928 1050969200 11.370 100 96 185 2 1136029280 66878752 18181328
02:18:44 844 8192000000 10035214400 10412236800 1062042448 11.476 100 95 179 3 1152429024 72355440 18031136
02:19:46 1149 8192000000 10041349360 10419617792 1067510608 11.528 100 92 180 1 1161985056 74689680 19784768
02:20:48 5391 8192000000 10025845776 10408226816 1074967888 11.599 100 78 140 1 1151388752 63658256 12762608
02:21:54 1165 8192000000 10028688544 10412191744 1086390240 11.708 100 97 187 0 1151280928 45551520 19339168
02:22:56 10363 8192000000 10070240816 10457350144 1090425184 11.747 100 70 135 0 1189493968 85596912 13471872
02:24:07 4504 8192000000 10043456976 10433548288 1096208528 11.802 100 75 141 1 1188072144 78371424 13492192
02:25:12 1870 8192000000 10071524528 10463416320 1095654656 11.796 100 86 148 3 1190228848 80033344 14540848
02:26:15 1010 8192000000 10085628608 10476294144 1100921216 11.846 100 92 183 1 1203277264 84243440 18112608
02:27:17 1047 8192000000 10062762144 10453008384 1111323872 11.945 100 94 170 1 1188320336 58679488 18316976
02:28:19 1092 8192000000 10052048224 10436796416 1095209728 11.792 100 97 183 0 1171761936 57412688 19139520
02:29:21 1205 8192000000 10055100176 10439118848 1091464592 11.757 100 96 187 2 1176030096 65261152 19304352
02:30:23 982 8192000000 10043420224 10422620160 1072657936 11.577 99 92 178 4 1166444128 75777296 18008896
02:31:25 1178 8192000000 10066037280 10450235392 1081773072 11.664 100 97 185 1 1190086064 88984304 19328688
02:32:27 1195 8192000000 9999981760 10375618560 1055766224 11.416 100 95 180 4 1121346736 47875392 17705120
02:33:29 1307 8192000000 10009648400 10383532032 1054546176 11.404 100 96 185 0 1130354320 56778848 19029296
02:34:31 1149 8192000000 9984937872 10363613184 1056806976 11.426 100 86 167 2 1104572320 31453488 16311856
02:35:33 1082 8192000000 10011708768 10384994304 1044367824 11.307 99 97 191 1 1132435440 66975536 21092080
02:36:42 1062 8192000000 9973725712 10350837760 1034788832 11.215 100 78 146 3 1098305360 48462240 15054288
02:37:46 8490 8192000000 9978236736 10357911552 1037341152 11.239 100 67 129 2 1101616544 50439200 13836192
02:39:01 2554 8192000000 10003522576 10381070336 1047908720 11.341 99 85 163 1 1123092592 57939168 17244704
02:40:08 13701 8192000000 10025007024 10412949504 1067042448 11.524 100 63 118 0 1147351728 67730160 12579120
02:41:26 14430 8192000000 10046259024 10441719808 1083261328 11.679 100 66 123 1 1168688432 71494464 13932640
02:42:46 6326 8192000000 10080454336 10472656896 1100302864 11.841 100 81 151 2 1197177200 81329920 15544416
02:43:55 1980 8192000000 10103072192 10496622592 1105838400 11.893 100 80 154 0 1221024128 99235792 15949936
02:45:00 3616 8192000000 10128323552 10520592384 1119020160 12.018 100 77 148 0 1247719520 113943968 14755392
02:46:09 10667 8192000000 10184095936 10578808832 1133116160 12.151 100 80 154 0 1291267376 141426256 16724960
02:47:23 1360 8192000000 10208078256 10601373696 1149871584 12.308 100 97 188 2 1319330032 149385280 20073168
02:48:29 1948 8192000000 10227912944 10627559424 1156390096 12.369 100 77 149 1 1337756192 165134320 16231776
02:49:34 993 8192000000 10262657824 10661277696 1170402656 12.501 100 93 183 1 1375261808 185609632 19249520
02:50:39 1021 8192000000 10285804096 10698584064 1179061328 12.581 100 91 181 0 1396972896 197940688 19970880
02:51:43 1290 8192000000 10312879824 10719289344 1180513264 12.595 100 91 177 0 1424367200 224517472 19336464
02:52:48 945 8192000000 10344395584 10749730816 1193429792 12.715 100 96 190 0 1454302848 237956576 22916480
02:53:51 930 8192000000 10397301776 10806677504 1192604064 12.708 100 96 187 1 1504656816 290269808 21782944
02:54:56 1135 8192000000 10418391552 10826649600 1194624096 12.726 100 93 178 1 1525091488 309005280 21462112
02:55:59 783 8192000000 10470744304 10895556608 1199276736 12.770 100 98 194 1 1579581088 357046272 23258080
02:57:02 847 8192000000 10452719488 10874359808 1177571920 12.568 99 94 188 0 1558298784 358067424 22659440
02:58:09 703 8192000000 10503345376 10932305920 1190562192 12.689 100 96 194 0 1608964448 395123392 23278864
02:59:11 1337 8192000000 10470685488 10912153600 1183448960 12.622 99 61 117 0 1579033808 380280560 15304288
03:00:27 8802 8192000000 10455371424 10886991872 1179881984 12.589 100 41 81 0 1556254608 365700544 10672080
03:01:53 12512 8192000000 10442601184 10896121856 1176929408 12.562 100 45 89 0 1555053744 365908624 12215712
03:03:11 1128 8192000000 10460192352 10905309184 1170839888 12.505 100 95 185 3 1562385296 369405120 22140288
03:04:13 1944 8192000000 10429019168 10902511616 1164240144 12.443 100 61 120 0 1533434272 354115104 15079024
03:05:17 12549 8192000000 10379513024 10875949056 1156746448 12.373 98 49 98 0 1509135984 340203648 12185888
03:06:38 10177 8192000000 10372948112 10820796416 1157783872 12.383 99 36 72 1 1474738240 308153360 8801008
03:08:42 68555 8192000000 10256271920 10731704320 1108176928 11.915 97 17 34 2 1356660368 244430640 4052800
03:10:53 65803 8192000000 10092238720 10599895040 1071448400 11.566 95 22 44 0 1175145840 98788560 4908880
03:13:00 106012 8192000000 10071042064 10499506176 1092985440 11.771 98 32 60 0 1164649936 64942928 6721568
03:15:47 21661 8192000000 10121504480 10571636736 1124522256 12.070 100 54 95 0 1228552784 94364384 9666144
03:17:09 86629 8192000000 10111486560 10593140736 1109239584 11.925 99 16 31 0 1204355824 91885408 3230832
03:19:41 74648 8192000000 10096232384 10578055168 1131070128 12.131 100 14 28 0 1220495104 86272688 3152288
03:21:56 136913 8192000000 10063509872 10522456064 1100170448 11.839 97 19 38 1 1146337312 41667872 4498992
03:26:15 76313 8192000000 10054869712 10505646080 1130667520 12.128 100 22 43 0 1166501600 31539856 4294224
03:28:32 38862 8192000000 10095781936 10563317760 1131330816 12.134 99 31 62 1 1199255904 61722144 6202944
03:30:14 20913 8192000000 10123328208 10564210688 1131554880 12.136 99 40 78 1 1205939072 66110784 8273408
03:31:37 53704 8192000000 10132734960 10605240320 1143024624 12.244 100 27 54 1 1246703328 97585440 6093264
03:33:32 52666 8192000000 10096690368 10568884224 1134458464 12.163 99 15 29 0 1229686624 90938752 4289408
03:35:37 64786 8192000000 10109992240 10588327936 1140841680 12.223 99 14 28 1 1239921216 96072112 3007424
03:37:42 49392 8192000000 10111624224 10565025792 1134122224 12.160 98 21 42 0 1182241984 43177440 4942320
03:39:34 28782 8192000000 10187935344 10648563712 1158794544 12.392 100 40 80 0 1281547984 114023712 8729728
03:41:04 142464 8192000000 10117705872 10611314688 1093720624 11.778 95 12 24 0 1161223824 64853104 2650096
03:44:28 30819 8192000000 10120038864 10568056832 1147230368 12.283 100 44 86 1 1209271296 53206848 8834080
03:46:14 20658 8192000000 10129057328 10549882880 1145615024 12.268 100 59 119 0 1237634048 80421056 11597968
03:47:38 119571 8192000000 10129137264 10604523520 1128475792 12.107 98 30 60 0 1222190592 87381936 6332864
03:50:39 49774 8192000000 10099863168 10573099008 1118018960 12.008 98 19 36 1 1207936048 85651712 4265376
03:53:44 37786 8192000000 10043963952 10501271552 1065380080 11.508 95 23 43 1 1124959824 55385504 4194240
03:55:23 60609 8192000000 10116253600 10559041536 1113994640 11.970 99 24 46 1 1195761552 76560432 5206480
03:57:25 81598 8192000000 10088215008 10568339456 1077539616 11.624 96 12 24 0 1178593472 98308512 2745344
03:59:47 35700 8192000000 10094295344 10520150016 1107183712 11.906 98 45 90 1 1170977008 53971392 9821904
04:01:25 31180 8192000000 10058841296 10497953792 1089450000 11.737 98 22 44 0 1130141376 36351184 4340192
04:02:58 31209 8192000000 10070947888 10495430656 1099432336 11.832 98 45 90 2 1176674656 68162320 9080000
04:04:45 15736 8192000000 10085123776 10506604544 1090054848 11.743 97 68 132 2 1160565664 56032656 14478160
04:06:02 18871 8192000000 10133392576 10544676864 1122076528 12.047 100 68 131 1 1219979024 84242032 13660464
04:07:21 38840 8192000000 10119061488 10542460928 1095497536 11.795 98 39 78 0 1200509856 97000624 8011696
04:09:06 30869 8192000000 10079010224 10519244800 1096421248 11.804 98 38 75 1 1157602448 54030704 7150496
04:10:42 73300 8192000000 10074881216 10533924864 1126077280 12.084 100 28 56 0 1200861200 69637696 5146224
04:12:59 45419 8192000000 10062866864 10530205696 1125834048 12.082 100 13 26 0 1180204080 51352944 3017088
04:14:45 73116 8192000000 10106879552 10554687488 1104521616 11.881 98 22 44 0 1186936896 77740800 4674480
04:17:04 73163 8192000000 10083279120 10599788544 1128555984 12.108 100 12 24 0 1190069008 59009216 2503808
04:19:20 109286 8192000000 10078626112 10522394624 1105326576 11.888 97 23 45 1 1133602912 24255360 4020976
04:22:13 116645 8192000000 10120607024 10582011904 1085872736 11.703 95 17 34 1 1173201600 84089168 3239696
04:25:10 29557 8192000000 10074040144 10504658944 1126094768 12.085 99 32 62 0 1166368576 34150400 6123408
04:26:40 14483 8192000000 10107179808 10544082944 1145085744 12.263 100 59 116 0 1196913536 40406608 11421184
04:27:59 140002 8192000000 10085038160 10593316864 1103498672 11.871 96 23 44 4 1162424400 54599840 4325888
04:31:20 129853 8192000000 10129576720 10576162816 1125079904 12.075 98 12 24 0 1208121440 80553472 2488064
04:34:33 47853 8192000000 10094669792 10597601280 1142623744 12.240 100 6 12 0 1232205776 88198688 1383344
04:37:01 47708 8192000000 9269395984 9594159104 413458416 4.804 37 3 6 0 429857808 15859056 540336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-03-2022 04:40:04 to 27-03-2022 04:40:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-03-2022 04:40:20 to 27-03-2022 04:40:22.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          215381
MON$OLDEST_ACTIVE               215382
MON$OLDEST_SNAPSHOT             215382
MON$NEXT_TRANSACTION            215382
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-30 07:30:08.6030 GMT
MON$PAGES                       2072416
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             760
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2745 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2745 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2745 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Mar 27 04:37:52 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Mar 27 04:38:01 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

27.03.22 04:40:24. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-03-27 04:40:26 No severe PSQL-related problems occured
27.03.22 04:40:27. End of report.