Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.07.2022 16:44
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 16172812 kB
MemAvailable 31063488 kB
Buffers 0 kB
Cached 15557236 kB
SwapCached 2500 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-23 16:42:56
DTS_END                         2022-07-23 16:42:56.9150
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53214
TRN_ID                          243352
ATT_ID                          650
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: 9188.73 20225 1102647
customer order: creation 179.92 349 21590
customer order: refuse 15.31 337 1837
order to supplier: creation 47.21 1317 5665
order to supplier: removal 3.53 83637 424
invoice (draft): creation 46.55 3241 5586
invoice (draft): removal 51.89 722 6227
invoice accept: apply 43.24 17732 5189
invoice accept: cancel 46.19 807 5543
customer reserve: creation 3913.07 180 469568
customer reserve: removal 4549.75 51 545970
realization accept: apply 86.20 181 10344
realization accept: cancel 73.35 110 8802
payment from customer: creation 61.02 114 7322
payment from customer: removal 5.88 78 706
payment to supplier: creation 50.02 141 6002
payment to supplier: removal 3.49 108 419
service: total inventory turnovers 4.58 1140 550
service: total monetary turnovers 6.61 33 793
service: aggregate perf. data 0.89 17429 107
service: refresh index statistics 0.03 276784 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-07-2022 16:44:38 to 23-07-2022 16:44:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2120
TEST_TIME 2 4347
TEST_TIME 3 5164
TEST_TIME 4 4088
TEST_TIME 5 3954
TEST_TIME 6 4681
TEST_TIME 7 4455
TEST_TIME 8 4729
TEST_TIME 9 4947
TEST_TIME 10 5311
TEST_TIME 11 5466
TEST_TIME 12 6285
TEST_TIME 13 6509
TEST_TIME 14 7153
TEST_TIME 15 7470
TEST_TIME 16 7685
TEST_TIME 17 7758
TEST_TIME 18 8039
TEST_TIME 19 8246
TEST_TIME 20 8176
TEST_TIME 21 8258
TEST_TIME 22 8391
TEST_TIME 23 8387
TEST_TIME 24 8283
TEST_TIME 25 8444
TEST_TIME 26 8975
TEST_TIME 27 9031
TEST_TIME 28 9061
TEST_TIME 29 9693
TEST_TIME 30 9923
TEST_TIME 31 9792
TEST_TIME 32 9833
TEST_TIME 33 9815
TEST_TIME 34 9878
TEST_TIME 35 9734
TEST_TIME 36 9550
TEST_TIME 37 9488
TEST_TIME 38 9573
TEST_TIME 39 9367
TEST_TIME 40 9477
TEST_TIME 41 9424
TEST_TIME 42 9419
TEST_TIME 43 9300
TEST_TIME 44 9225
TEST_TIME 45 9160
TEST_TIME 46 9159
TEST_TIME 47 9199
TEST_TIME 48 9359
TEST_TIME 49 9220
TEST_TIME 50 9076
TEST_TIME 51 9012
TEST_TIME 52 9260
TEST_TIME 53 9271
TEST_TIME 54 9179
TEST_TIME 55 9221
TEST_TIME 56 9233
TEST_TIME 57 9173
TEST_TIME 58 9155
TEST_TIME 59 9232
TEST_TIME 60 9196
TEST_TIME 61 9216
TEST_TIME 62 9253
TEST_TIME 63 9370
TEST_TIME 64 9288
TEST_TIME 65 9362
TEST_TIME 66 9382
TEST_TIME 67 9410
TEST_TIME 68 9395
TEST_TIME 69 9360
TEST_TIME 70 9248
TEST_TIME 71 9190
TEST_TIME 72 9209
TEST_TIME 73 9146
TEST_TIME 74 9169
TEST_TIME 75 9170
TEST_TIME 76 9110
TEST_TIME 77 9125
TEST_TIME 78 9082
TEST_TIME 79 9012
TEST_TIME 80 9010
TEST_TIME 81 8983
TEST_TIME 82 8959
TEST_TIME 83 8940
TEST_TIME 84 8948
TEST_TIME 85 8940
TEST_TIME 86 9010
TEST_TIME 87 8981
TEST_TIME 88 9079
TEST_TIME 89 9055
TEST_TIME 90 9052
TEST_TIME 91 9148
TEST_TIME 92 9130
TEST_TIME 93 9191
TEST_TIME 94 9234
TEST_TIME 95 9213
TEST_TIME 96 9217
TEST_TIME 97 9229
TEST_TIME 98 9159
TEST_TIME 99 9147
TEST_TIME 100 9118
TEST_TIME 101 9099
TEST_TIME 102 9067
TEST_TIME 103 9015
TEST_TIME 104 9025
TEST_TIME 105 8970
TEST_TIME 106 8943
TEST_TIME 107 9055
TEST_TIME 108 9029
TEST_TIME 109 9021
TEST_TIME 110 9040
TEST_TIME 111 9056
TEST_TIME 112 9064
TEST_TIME 113 9089
TEST_TIME 114 9072
TEST_TIME 115 9088
TEST_TIME 116 9120
TEST_TIME 117 9144
TEST_TIME 118 9156
TEST_TIME 119 9249
TEST_TIME 120 9189

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-07-2022 16:44:42 to 23-07-2022 16:44:45.

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 1537000 1537000 0 0.00 0 17579 6 0 0
doc_list_biud 1536998 1536998 0 0.00 0 26742 24 0 0
sp_add_invoice_to_stock 5335 5189 146 2.73 45 166711 17732 0 146
sp_cancel_adding_invoice 5543 5543 0 0.00 4 71269 807 0 0
sp_cancel_client_order 1837 1837 0 0.00 4 10268 338 0 0
sp_cancel_customer_reserve 545970 545970 0 0.00 0 23506 51 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 10038 79 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 6344 108 0 0
sp_cancel_supplier_invoice 6237 6227 10 0.16 1 19530 723 0 10
sp_cancel_supplier_order 424 424 0 0.00 4 811097 83637 0 0
sp_cancel_write_off 8802 8802 0 0.00 1 11426 110 0 0
sp_client_order 21590 21590 0 0.00 3 24187 350 0 0
sp_customer_reserve 469568 469568 0 0.00 0 43133 181 0 0
sp_fill_shopping_cart 32822 32822 0 0.00 1 16893 40 0 0
sp_fill_shopping_cart_clo_res 469567 469567 0 0.00 0 10648 12 0 0
sp_get_clo_for_invoice 5188 5188 0 0.00 0 21049 293 0 0
sp_kill_cost_storno 15470 15470 0 0.00 0 10420 53 0 0
sp_kill_qstorno_ret_qs2qd 1107393 1107393 0 0.00 0 23425 28 0 0
sp_kill_qty_storno 584335 584335 0 0.00 0 26733 61 0 0
sp_lock_selected_doc 21441 21441 0 0.00 0 8230 4 0 0
sp_make_cost_storno 28856 28856 0 0.00 0 17336 57 0 0
sp_multiply_rows_for_pdistr 28855 28855 0 0.00 0 11712 29 0 0
sp_multiply_rows_for_qdistr 21589 21589 0 0.00 0 24178 135 0 0
sp_pay_from_customer 7322 7322 0 0.00 4 14947 114 0 0
sp_pay_to_supplier 6002 6002 0 0.00 4 13167 141 0 0
sp_payment_common 13323 13323 0 0.00 1 14905 34 0 0
sp_qd_handle_on_cancel_clo 1837 1837 0 0.00 0 10253 297 0 0
sp_qd_handle_on_invoice_upd_sts 10731 10731 0 0.00 0 26723 311 0 0
sp_qd_handle_on_reserve_upd_sts 10344 10344 0 0.00 0 18523 63 0 0
sp_reserve_write_off 10344 10344 0 0.00 6 18541 181 0 0
sp_supplier_invoice 5586 5586 0 0.00 5 113973 3242 0 0
sp_supplier_order 5665 5665 0 0.00 4 42787 1318 0 0
srv_aggregate_perf_data 108 107 1 0.92 2454 107699 17430 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1186 114769 47295 0 0
srv_find_qd_qs_mism 1053453 1053453 0 0.00 0 13454 6 0 0
srv_make_invnt_saldo 550 550 0 0.00 47 32615 1141 0 0
srv_make_money_saldo 793 793 0 0.00 1 4109 33 0 0
srv_recalc_idx_stat 3 3 0 0.00 169161 370238 276784 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5189 5189 0 0.00 0 14280 141 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3841 83 0 0
t$perf-norm:sp_cancel_client_order 1837 1837 0 0.00 0 20982 30 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 649 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1153 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 409 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 5209 24 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 41041 1422 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2645 13 0 0
t$perf-norm:sp_client_order 21590 21590 0 0.00 0 20969 19 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 1086 6 0 0
t$perf-norm:sp_pay_from_customer 7322 7322 0 0.00 0 11605 9 0 0
t$perf-norm:sp_pay_to_supplier 6002 6002 0 0.00 0 6117 11 0 0
t$perf-norm:sp_reserve_write_off 10344 10344 0 0.00 0 8533 12 0 0
t$perf-norm:sp_supplier_invoice 5586 5586 0 0.00 0 4421 15 0 0
t$perf-norm:sp_supplier_order 5665 5665 0 0.00 0 9353 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 95 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 352 9 0 0
t$perf-norm:srv_make_invnt_saldo 550 550 0 0.00 0 1042 21 0 0
t$perf-norm:srv_make_money_saldo 793 793 0 0.00 0 1164 17 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 3 4 3 0 0
v_add_invoice_to_stock 10666 10374 292 2.73 0 7848 21 0 292
v_all_customers 39240 39240 0 0.00 0 59 1 0 0
v_all_suppliers 30896 30896 0 0.00 0 31 1 0 0
v_all_wares 43176 43176 0 0.00 0 42 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 1797 14 0 0
v_cancel_client_order 3674 3674 0 0.00 0 6000 11 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 964 5 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2035 5 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 3176 19 0 0
v_cancel_supplier_invoice 844 824 20 2.36 0 3392 17 0 20
v_cancel_supplier_order 848 848 0 0.00 0 6262 20 0 0
v_cancel_write_off 1998 1998 0 0.00 0 4868 22 0 0
v_max_id_clo_ord 5664 5664 0 0.00 0 3484 3 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 6506 18 0 0
v_max_id_ord_sup 5570 5570 0 0.00 0 4407 4 0 0
v_max_non_paid_invoice 1787 1787 0 0.00 0 898 3 0 0
v_max_non_paid_realizn 3999 3999 0 0.00 0 950 4 0 0
v_min_id_clo_ord 5664 5664 0 0.00 0 10905 15 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 3033 16 0 0
v_min_id_ord_sup 5570 5570 0 0.00 0 4164 8 0 0
v_min_non_paid_invoice 6001 6001 0 0.00 0 11747 30 0 0
v_min_non_paid_realizn 7322 7322 0 0.00 0 14647 24 0 0
v_our_firm 10582 10582 0 0.00 0 26 1 0 0
v_reserve_write_off 20688 20688 0 0.00 0 5239 5 0 0
x_lock_depdocs_on_canc_invoice 11358 11358 0 0.00 0 16483 106 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 10982 343 0 0
x_make_qstorno_1000_1200 5665 5665 0 0.00 1 42766 1233 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 2 109700 3097 0 0
x_make_qstorno_2100_3300 469568 469568 0 0.00 0 43130 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-07-2022 16:44:46 to 23-07-2022 16:44:50.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:59 19 8192000000 8806522800 8820264960 11866608 0.144 3 3 4 0 12050512 76800 107104
14:14:01 29471 8192000000 9905479616 10219155456 986031440 10.743 100 83 163 2 1013823520 6247472 21544608
14:15:34 21078 8192000000 9952146624 10274451456 1032754592 11.195 100 86 171 0 1063065632 8260096 22050944
14:17:02 14771 8192000000 9984760080 10315341824 1065551632 11.510 100 85 170 0 1096818224 9772176 21494416
14:18:30 10155 8192000000 10026807648 10375782400 1115952800 11.989 100 74 148 1 1143924592 10570608 17401184
14:19:44 6134 8192000000 10074027680 10436014080 1168596256 12.484 100 76 150 0 1197789520 11628240 17565024
14:20:52 2444 8192000000 10133271216 10501144576 1219375296 12.956 100 85 170 0 1253024288 14269248 19379744
14:21:57 3975 8192000000 10154954176 10533740544 1245355328 13.196 100 75 150 1 1274782608 13258976 16168304
14:23:03 1448 8192000000 10193761696 10578165760 1280756864 13.520 100 80 157 1 1310229264 12698528 16773872
14:24:07 1648 8192000000 10211471728 10595295232 1296701408 13.665 100 91 182 0 1329156896 12663552 19791936
14:25:10 10577 8192000000 10206813440 10599923712 1300062720 13.696 100 71 143 2 1328827040 12913440 15850880
14:26:22 2693 8192000000 10201015472 10610233344 1308330080 13.771 100 41 82 0 1324997472 7988480 8678912
14:27:26 9294 8192000000 10193276768 10612260864 1314619808 13.828 100 15 28 0 1323455136 5620336 3214992
14:28:37 10885 8192000000 10203764352 10626293760 1323670768 13.910 100 16 32 0 1333680208 6129088 3880352
14:29:48 37114 8192000000 10232105040 10644099072 1328655776 13.955 100 39 74 0 1343026688 6090464 8280448
14:31:26 7600 8192000000 10222160608 10644860928 1332936880 13.994 100 30 59 1 1345512064 5981120 6594064
14:32:35 8463 8192000000 10235727552 10655215616 1335713600 14.019 100 20 40 1 1349777488 9697328 4366560
14:33:45 6965 8192000000 10197846896 10609917952 1284593056 13.555 98 22 44 1 1295713232 6642544 4477632
14:34:53 27837 8192000000 10156236544 10564063232 1131592928 12.136 87 16 32 0 1140162896 4884160 3685808
14:36:22 6773 8192000000 10121678048 10516172800 1179874080 12.589 94 18 37 1 1189279248 5522416 3882752
14:37:29 7261 8192000000 10153249136 10552651776 1256426752 13.297 100 41 82 0 1272144464 5392416 10325296
14:38:37 18622 8192000000 10167515296 10574938112 1260370592 13.333 98 23 46 1 1269852496 5039568 4442336
14:40:35 6151 8192000000 10184673568 10591518720 1282063136 13.532 99 37 71 2 1296126304 5399504 8663664
14:41:47 10426 8192000000 10194634624 10608128000 1303126624 13.724 100 19 36 2 1312527616 5184608 4216384
14:43:06 13658 8192000000 10234880512 10646073344 1333940336 14.003 100 44 88 0 1350857744 6452688 10464720
14:44:25 36612 8192000000 10252676416 10675322880 1352208688 14.167 100 43 85 0 1374114000 11991024 9914288
14:46:06 90476 8192000000 10278271712 10706374656 1372316352 14.348 100 43 84 1 1394956032 11288720 11350960
14:48:43 20374 8192000000 10280830768 10717507584 1381050688 14.426 100 22 44 0 1399957264 13466736 5439840
14:50:09 23280 8192000000 10304501248 10738335744 1391075808 14.515 100 38 72 0 1413233936 13786192 8371936
14:51:38 12936 8192000000 10321569520 10755440640 1406227072 14.650 100 44 88 0 1436072416 19331712 10513632
14:52:55 8874 8192000000 10331821760 10766159872 1412320624 14.705 100 41 79 1 1441826624 19796112 9709888
14:54:19 2354 8192000000 10340729808 10769723392 1419265408 14.766 100 60 120 0 1450592656 17058896 14268352
14:55:27 2214 8192000000 10337456048 10755067904 1406591408 14.654 100 74 144 0 1442106736 18601984 16913344
14:56:35 2687 8192000000 10327457632 10739232768 1387683168 14.485 99 75 150 0 1424834400 19470224 17681008
14:57:40 16573 8192000000 10308623744 10742427648 1367065520 14.301 97 37 75 1 1396103904 19825264 9213120
14:59:02 3162 8192000000 10307544608 10723934208 1369891888 14.326 99 77 154 1 1407152896 18691184 18569824
15:00:15 1185 8192000000 10289541216 10691751936 1338107200 14.040 98 84 168 2 1383349008 25328240 19913568
15:01:19 17176 8192000000 10288822640 10702082048 1351614272 14.162 99 42 81 3 1384194912 22075152 10505488
15:02:41 2457 8192000000 10291115408 10698104832 1348437648 14.133 99 53 105 0 1388765072 27171552 13155872
15:03:49 8512 8192000000 10281694144 10704023552 1359621968 14.234 100 36 72 0 1395695264 26845520 9227776
15:05:03 18280 8192000000 10299468784 10718056448 1356616144 14.207 99 35 71 1 1398719360 33025280 9077936
15:06:31 5079 8192000000 10279090512 10691600384 1324484704 13.917 98 50 98 1 1369006000 31779312 12741984
15:07:42 14979 8192000000 10290642576 10711609344 1353238544 14.177 99 28 56 0 1399059840 37438912 8382384
15:08:59 6014 8192000000 10304266048 10730852352 1372866640 14.353 100 49 98 1 1412331312 27636512 11828160
15:10:10 18075 8192000000 10293501760 10726494208 1364515616 14.278 100 22 43 1 1400652576 29951712 6185248
15:11:32 33950 8192000000 10280488432 10713370624 1332431360 13.989 98 20 40 1 1368837296 31096336 5309600
15:13:12 48330 8192000000 10293905536 10719330304 1358398048 14.223 99 44 86 1 1382813136 13811536 10603552
15:15:12 11259 8192000000 10261258608 10699534336 1346100160 14.112 99 24 48 0 1365186128 13453792 5632176
15:16:24 40485 8192000000 10279973728 10720391168 1376058016 14.381 100 12 22 1 1389994288 11740736 2195536
15:18:04 38302 8192000000 10296221744 10738679808 1389188496 14.499 100 25 50 0 1407014480 11834704 5991280
15:19:46 102556 8192000000 10311328000 10748870656 1397660864 14.574 100 35 68 1 1425992128 19968336 8362928
15:22:30 72168 8192000000 10318368016 10769166336 1405446928 14.643 100 25 51 0 1430985168 19332848 6205392
15:25:08 30178 8192000000 10328567440 10769543168 1411578960 14.698 100 30 58 2 1434596848 15571104 7446784
15:26:42 46620 8192000000 10329638240 10777538560 1417562720 14.751 100 24 49 0 1442770096 19247120 5960256
15:28:40 75852 8192000000 10313277408 10777960448 1388179616 14.490 98 15 30 0 1410637456 18376320 4081520
15:31:04 40467 8192000000 10317015824 10771701760 1417240992 14.748 100 15 30 0 1432233184 11081424 3910768
15:32:48 96767 8192000000 10324694592 10786385920 1410867488 14.692 99 26 51 0 1438992544 21035552 7089504
15:35:25 73413 8192000000 10321358240 10775130112 1400532752 14.600 99 11 21 1 1414676320 12064080 2079488
15:37:40 54869 8192000000 10294027024 10756927488 1360000528 14.237 96 15 30 0 1372140112 8976944 3162640
15:39:42 23588 8192000000 10333999408 10770624512 1407392544 14.661 100 45 90 0 1427015296 9906992 9715760
15:41:06 86858 8192000000 10287823376 10739793920 1307290048 13.761 93 18 36 0 1322173040 11250688 3632304
15:43:33 58339 8192000000 10292777264 10742153216 1338256384 14.042 96 12 24 0 1360805920 19939936 2609600
15:45:32 70806 8192000000 10306591072 10734366720 1330082816 13.968 96 31 61 0 1347270608 10534320 6653472
15:47:49 114769 8192000000 10279682176 10735345664 1290676704 13.610 94 13 24 0 1309851824 16731664 2443456
15:50:43 110208 8192000000 10258798768 10700255232 1334067968 14.004 98 9 18 0 1353802320 17635232 2099120
15:53:38 57593 8192000000 10264609696 10701266944 1291434640 13.617 95 39 78 0 1310773984 11231136 8108208
15:55:48 3767 8192000000 10268699424 10705768448 1354083664 14.184 100 41 80 0 1374552896 11589936 8879296
15:57:05 45447 8192000000 10251468304 10699120640 1323106944 13.905 97 23 43 1 1337844112 9809136 4928032
15:58:51 73827 8192000000 10262564400 10717941760 1354041744 14.184 99 14 28 0 1365996192 8344832 3609616
16:01:53 73545 8192000000 10265179200 10728013824 1368340240 14.312 100 13 26 0 1382306288 10727216 3238832
16:04:07 69302 8192000000 10234458176 10689445888 1317576432 13.855 97 19 38 1 1331267648 9296816 4394400
16:06:32 38122 8192000000 10260436928 10710962176 1357819888 14.218 100 29 58 0 1377545408 13161936 6563584
16:08:11 68165 8192000000 10266862400 10709188608 1343590976 14.090 99 17 32 0 1356808304 9229408 3987920
16:10:24 94044 8192000000 10268114560 10711134208 1314763760 13.829 96 12 24 0 1338094544 20552096 2778688
16:13:13 63844 8192000000 10288559168 10742513664 1377769792 14.397 100 15 30 0 1396625312 15967632 2887888
16:15:17 92958 8192000000 10307230896 10756079616 1386150640 14.472 100 8 16 0 1403572896 15978096 1444160
16:17:53 20834 8192000000 10311800464 10753527808 1380283552 14.419 99 34 68 0 1398106512 10459328 7363632
16:19:39 62107 8192000000 10298373728 10745524224 1391404096 14.518 100 16 32 2 1404421120 9684592 3332432
16:21:41 73322 8192000000 10292875648 10762235904 1385646512 14.467 99 7 14 0 1399414416 12411824 1356080
16:23:55 89941 8192000000 10282462768 10736697344 1351137568 14.158 97 11 22 0 1362265568 8895712 2232288
16:26:31 60956 8192000000 10278152592 10727096320 1340680080 14.064 97 14 29 0 1358958640 14949440 3329120
16:28:32 72795 8192000000 10271159376 10731667456 1338927664 14.048 98 9 18 0 1363421200 22084640 2408896
16:30:52 40309 8192000000 10270861152 10715787264 1327735360 13.947 97 29 58 0 1346316208 12682224 5898624
16:32:37 76596 8192000000 10262948016 10713563136 1306659440 13.756 95 15 31 0 1322088944 12266752 3162752
16:34:56 48767 8192000000 10282386256 10730270720 1342715616 14.082 98 35 67 0 1362295392 11692880 7886896
16:36:52 53672 8192000000 10270018448 10721374208 1333926544 14.003 97 30 60 0 1353405792 12599280 6879968
16:38:56 98989 8192000000 10282044688 10732826624 1284407968 13.553 94 15 30 0 1302643376 15037424 3197984
16:41:38 93196 8192000000 9606840256 9956151296 723166736 8.111 53 5 10 0 739219408 15387856 664816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-07-2022 16:44:52 to 23-07-2022 16:45:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 146 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-07-2022 16:45:08 to 23-07-2022 16:45:10.

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          243351
MON$OLDEST_ACTIVE               243580
MON$OLDEST_SNAPSHOT             243580
MON$NEXT_TRANSACTION            243580
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                       2022016
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Jul 23 16:43:26 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Jul 23 16:43:27 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

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