Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.12.2022 22:40
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 15105052 kB
MemAvailable 30113960 kB
Buffers 0 kB
Cached 16631880 kB
SwapCached 3268 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-12-22 22:38:35
DTS_END                         2022-12-22 22:38:35.0780
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/34402
TRN_ID                          242551
ATT_ID                          707
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: 9197.74 20009 1103729
customer order: creation 173.72 342 20846
customer order: refuse 15.34 374 1841
order to supplier: creation 46.03 1257 5523
order to supplier: removal 3.44 88419 413
invoice (draft): creation 46.01 3326 5521
invoice (draft): removal 51.62 706 6194
invoice accept: apply 42.21 16567 5065
invoice accept: cancel 46.91 773 5629
customer reserve: creation 3861.53 165 463383
customer reserve: removal 4603.41 52 552409
realization accept: apply 85.20 192 10224
realization accept: cancel 88.43 106 10612
payment from customer: creation 61.52 136 7382
payment from customer: removal 6.18 90 741
payment to supplier: creation 50.34 132 6041
payment to supplier: removal 3.42 69 410
service: total inventory turnovers 4.83 982 579
service: total monetary turnovers 6.82 45 818
service: aggregate perf. data 0.78 16143 94
service: refresh index statistics 0.03 270297 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-12-2022 22:41:04 to 22-12-2022 22:41:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4182
TEST_TIME 2 3911
TEST_TIME 3 5439
TEST_TIME 4 4890
TEST_TIME 5 4741
TEST_TIME 6 4676
TEST_TIME 7 5039
TEST_TIME 8 5067
TEST_TIME 9 5429
TEST_TIME 10 5386
TEST_TIME 11 5786
TEST_TIME 12 5963
TEST_TIME 13 5916
TEST_TIME 14 6309
TEST_TIME 15 6642
TEST_TIME 16 6917
TEST_TIME 17 7063
TEST_TIME 18 7368
TEST_TIME 19 7879
TEST_TIME 20 8045
TEST_TIME 21 8636
TEST_TIME 22 8675
TEST_TIME 23 8832
TEST_TIME 24 8695
TEST_TIME 25 8888
TEST_TIME 26 8956
TEST_TIME 27 9338
TEST_TIME 28 9980
TEST_TIME 29 10070
TEST_TIME 30 9945
TEST_TIME 31 9864
TEST_TIME 32 9862
TEST_TIME 33 10012
TEST_TIME 34 9796
TEST_TIME 35 9626
TEST_TIME 36 9560
TEST_TIME 37 9558
TEST_TIME 38 9511
TEST_TIME 39 9597
TEST_TIME 40 9606
TEST_TIME 41 9469
TEST_TIME 42 9429
TEST_TIME 43 9337
TEST_TIME 44 9213
TEST_TIME 45 9249
TEST_TIME 46 9275
TEST_TIME 47 9136
TEST_TIME 48 9142
TEST_TIME 49 9172
TEST_TIME 50 9049
TEST_TIME 51 9015
TEST_TIME 52 9012
TEST_TIME 53 9032
TEST_TIME 54 8931
TEST_TIME 55 8902
TEST_TIME 56 8839
TEST_TIME 57 8801
TEST_TIME 58 8903
TEST_TIME 59 8889
TEST_TIME 60 8938
TEST_TIME 61 8962
TEST_TIME 62 8945
TEST_TIME 63 8971
TEST_TIME 64 9020
TEST_TIME 65 9164
TEST_TIME 66 9177
TEST_TIME 67 9273
TEST_TIME 68 9244
TEST_TIME 69 9336
TEST_TIME 70 9246
TEST_TIME 71 9474
TEST_TIME 72 9461
TEST_TIME 73 9397
TEST_TIME 74 9339
TEST_TIME 75 9293
TEST_TIME 76 9234
TEST_TIME 77 9206
TEST_TIME 78 9179
TEST_TIME 79 9154
TEST_TIME 80 9137
TEST_TIME 81 9240
TEST_TIME 82 9189
TEST_TIME 83 9250
TEST_TIME 84 9246
TEST_TIME 85 9238
TEST_TIME 86 9249
TEST_TIME 87 9283
TEST_TIME 88 9277
TEST_TIME 89 9274
TEST_TIME 90 9373
TEST_TIME 91 9377
TEST_TIME 92 9415
TEST_TIME 93 9412
TEST_TIME 94 9452
TEST_TIME 95 9392
TEST_TIME 96 9313
TEST_TIME 97 9256
TEST_TIME 98 9256
TEST_TIME 99 9219
TEST_TIME 100 9157
TEST_TIME 101 9120
TEST_TIME 102 9162
TEST_TIME 103 9184
TEST_TIME 104 9162
TEST_TIME 105 9160
TEST_TIME 106 9185
TEST_TIME 107 9185
TEST_TIME 108 9206
TEST_TIME 109 9242
TEST_TIME 110 9291
TEST_TIME 111 9291
TEST_TIME 112 9287
TEST_TIME 113 9285
TEST_TIME 114 9311
TEST_TIME 115 9390
TEST_TIME 116 9325
TEST_TIME 117 9279
TEST_TIME 118 9291
TEST_TIME 119 9244
TEST_TIME 120 9201

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-12-2022 22:41:08 to 22-12-2022 22:41:11.

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 1532319 1532319 0 0.00 0 28354 6 0 0
doc_list_biud 1532318 1532318 0 0.00 0 28483 24 0 0
sp_add_invoice_to_stock 5207 5065 142 2.72 18 175214 16568 0 142
sp_cancel_adding_invoice 5629 5629 0 0.00 3 69868 773 0 0
sp_cancel_client_order 1841 1841 0 0.00 4 21961 374 0 0
sp_cancel_customer_reserve 552409 552409 0 0.00 0 25022 52 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 3 6904 91 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 6363 69 0 0
sp_cancel_supplier_invoice 6204 6194 10 0.16 1 28496 706 0 10
sp_cancel_supplier_order 413 413 0 0.00 6 928822 88420 0 0
sp_cancel_write_off 10612 10612 0 0.00 1 14987 106 0 0
sp_client_order 20846 20846 0 0.00 3 18588 342 0 0
sp_customer_reserve 463383 463383 0 0.00 0 78087 165 0 0
sp_fill_shopping_cart 31887 31887 0 0.00 1 11371 41 0 0
sp_fill_shopping_cart_clo_res 463376 463376 0 0.00 0 17265 13 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 20396 283 0 0
sp_kill_cost_storno 17392 17392 0 0.00 0 11077 53 0 0
sp_kill_qstorno_ret_qs2qd 1122037 1122037 0 0.00 0 28481 28 0 0
sp_kill_qty_storno 592379 592379 0 0.00 0 28482 62 0 0
sp_lock_selected_doc 21196 21196 0 0.00 0 7061 6 0 0
sp_make_cost_storno 28704 28704 0 0.00 0 28353 63 0 0
sp_multiply_rows_for_pdistr 28704 28704 0 0.00 0 10790 29 0 0
sp_multiply_rows_for_qdistr 20846 20846 0 0.00 0 13160 150 0 0
sp_pay_from_customer 7382 7382 0 0.00 4 28358 137 0 0
sp_pay_to_supplier 6041 6041 0 0.00 5 18484 132 0 0
sp_payment_common 13423 13423 0 0.00 2 16350 33 0 0
sp_qd_handle_on_cancel_clo 1841 1841 0 0.00 0 21954 340 0 0
sp_qd_handle_on_invoice_upd_sts 10686 10686 0 0.00 0 18491 295 0 0
sp_qd_handle_on_reserve_upd_sts 10224 10224 0 0.00 0 12971 70 0 0
sp_reserve_write_off 10224 10224 0 0.00 5 14768 192 0 0
sp_supplier_invoice 5521 5521 0 0.00 5 66428 3326 0 0
sp_supplier_order 5523 5523 0 0.00 5 32006 1257 0 0
srv_aggregate_perf_data 94 94 0 0.00 2736 106521 16144 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1556 165814 46355 0 0
srv_find_qd_qs_mism 1055065 1055065 0 0.00 0 8848 5 0 0
srv_make_invnt_saldo 579 579 0 0.00 42 31149 982 0 0
srv_make_money_saldo 818 818 0 0.00 1 16163 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 96217 481766 270297 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 16878 155 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 2952 98 0 0
t$perf-norm:sp_cancel_client_order 1841 1841 0 0.00 0 9391 21 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 1144 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 2539 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 322 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 3240 24 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 17441 1367 0 0
t$perf-norm:sp_cancel_write_off 991 991 0 0.00 0 6141 9 0 0
t$perf-norm:sp_client_order 20846 20846 0 0.00 0 7840 16 0 0
t$perf-norm:sp_customer_reserve 1036 1036 0 0.00 0 2988 8 0 0
t$perf-norm:sp_pay_from_customer 7382 7382 0 0.00 0 5900 11 0 0
t$perf-norm:sp_pay_to_supplier 6041 6041 0 0.00 0 6598 12 0 0
t$perf-norm:sp_reserve_write_off 10224 10224 0 0.00 0 9328 12 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 2187 11 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 3096 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 2321 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 242 5 0 0
t$perf-norm:srv_make_invnt_saldo 579 579 0 0.00 0 2313 26 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 6210 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10394 10110 284 2.73 0 5049 20 0 284
v_all_customers 38666 38666 0 0.00 0 26 1 0 0
v_all_suppliers 30850 30850 0 0.00 0 40 1 0 0
v_all_wares 41692 41692 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 995 8 0 0
v_cancel_client_order 3682 3682 0 0.00 0 6440 10 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 2828 8 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1561 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 2185 5 0 0
v_cancel_supplier_invoice 848 828 20 2.35 0 2249 21 0 20
v_cancel_supplier_order 826 826 0 0.00 0 629 5 0 0
v_cancel_write_off 1982 1982 0 0.00 0 4054 16 0 0
v_max_id_clo_ord 5522 5522 0 0.00 0 3847 2 0 0
v_max_id_clo_res 1036 1036 0 0.00 0 2059 15 0 0
v_max_id_ord_sup 5518 5518 0 0.00 0 5991 7 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 316 3 0 0
v_max_non_paid_realizn 3832 3832 0 0.00 0 3198 6 0 0
v_min_id_clo_ord 5522 5522 0 0.00 0 8098 6 0 0
v_min_id_clo_res 1036 1036 0 0.00 0 8222 26 0 0
v_min_id_ord_sup 5518 5518 0 0.00 0 5011 6 0 0
v_min_non_paid_invoice 6041 6041 0 0.00 0 8467 23 0 0
v_min_non_paid_realizn 7382 7382 0 0.00 0 16338 28 0 0
v_our_firm 10126 10126 0 0.00 0 167 1 0 0
v_reserve_write_off 20448 20448 0 0.00 0 5822 7 0 0
x_lock_depdocs_on_canc_invoice 11409 11409 0 0.00 0 12852 123 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 9784 315 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 1 31989 1179 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 2 66415 3169 0 0
x_make_qstorno_2100_3300 463382 463382 0 0.00 0 78085 150 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-12-2022 22:41:12 to 22-12-2022 22:41:17.

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:08:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:39 22783 8192000000 9899529696 10207232000 981735904 10.701 100 87 174 0 1010920512 6196016 22988592
20:11:07 12459 8192000000 9928824528 10248040448 1014427312 11.018 100 86 172 0 1044636928 8091552 22118064
20:12:26 13527 8192000000 9966068352 10294181888 1050855920 11.369 100 82 164 0 1081293808 9694560 20743328
20:13:45 8422 8192000000 9994470848 10336235520 1081792496 11.665 100 78 156 0 1111096672 10209856 19094320
20:15:04 6817 8192000000 10044706288 10397569024 1140825728 12.223 100 77 154 0 1169584128 11329920 17428480
20:16:14 4056 8192000000 10098494144 10464890880 1189492880 12.679 100 75 150 0 1218021984 11170736 17358368
20:17:19 2772 8192000000 10150760624 10524790784 1239908848 13.145 100 76 152 0 1268249136 12265264 16075024
20:18:24 5210 8192000000 10175832400 10554900480 1267884432 13.402 100 82 164 1 1299900848 13972320 18044096
20:19:31 2052 8192000000 10208331504 10595831808 1298027728 13.677 100 87 172 3 1330215600 13045248 19142624
20:20:35 38588 8192000000 10211749328 10620571648 1315380400 13.835 100 55 108 2 1336722864 9313904 12028560
20:22:14 4559 8192000000 10219728640 10626408448 1322391664 13.898 100 29 58 1 1335836144 7339664 6104816
20:23:21 9925 8192000000 10215558432 10631364608 1326381504 13.934 100 14 28 0 1335045712 5550992 3113216
20:24:32 11233 8192000000 10212217392 10635739136 1332291808 13.988 100 17 33 0 1341755824 5752304 3711712
20:25:45 25974 8192000000 10229056976 10653274112 1335346512 14.015 100 33 62 1 1347574592 4958560 7269520
20:27:14 5548 8192000000 10228875280 10649124864 1337087328 14.031 100 32 64 2 1351411680 6257392 8066960
20:28:21 6686 8192000000 10223900608 10643562496 1318562320 13.864 99 21 43 0 1333603392 10208640 4832432
20:29:31 9990 8192000000 10166386736 10574934016 1254097440 13.276 96 20 41 2 1263886512 4939856 4849216
20:30:42 25271 8192000000 10180401648 10588889088 1250842224 13.246 96 42 85 1 1266439632 4942960 10654448
20:32:10 4554 8192000000 10151970560 10546724864 1218523616 12.948 97 41 82 1 1235350880 7503552 9323712
20:33:18 7605 8192000000 10156429424 10553208832 1247902352 13.219 99 32 63 1 1260296384 5351872 7042160
20:34:26 15521 8192000000 10164743008 10570469376 1265380576 13.379 99 19 39 1 1275489152 5187792 4920784
20:35:44 22609 8192000000 10172196992 10585440256 1285770480 13.566 100 19 38 0 1295548640 5510768 4267392
20:37:07 5343 8192000000 10194809184 10609745920 1302915648 13.722 100 22 44 0 1314033968 6379856 4738464
20:38:15 7632 8192000000 10227950064 10642923520 1331031792 13.976 100 22 42 2 1340341680 4901552 4408336
20:39:28 8286 8192000000 10258283136 10666946560 1348227664 14.132 100 54 101 0 1367547376 8164384 11155328
20:40:40 48633 8192000000 10260793136 10683023360 1358017216 14.220 100 41 80 1 1378627520 10329152 10281152
20:42:32 43134 8192000000 10276789504 10699538432 1368519264 14.314 100 30 58 0 1385314176 10041120 6753792
20:44:19 28144 8192000000 10300021536 10725036032 1385945712 14.470 100 41 82 0 1412563840 16560816 10057312
20:46:02 8953 8192000000 10315651440 10738970624 1395320192 14.553 100 50 94 2 1424820432 17440512 12059728
20:47:18 4826 8192000000 10316874752 10745925632 1404865952 14.638 100 43 86 0 1431942992 16553824 10523216
20:48:25 10798 8192000000 10321972368 10751537152 1397830384 14.576 99 49 96 0 1432677200 23238208 11608608
20:49:41 10585 8192000000 10336096368 10767601664 1415303584 14.731 100 42 84 0 1447130368 20401136 11425648
20:51:01 6202 8192000000 10336439536 10761211904 1409443216 14.679 100 54 106 0 1440963760 18984800 12535744
20:52:12 1552 8192000000 10344188720 10767548416 1408933056 14.674 99 64 126 0 1449859680 25018896 15907728
20:53:19 1987 8192000000 10333071104 10747604992 1394992336 14.550 100 86 172 1 1439795184 24307600 20495248
20:54:27 6814 8192000000 10334927824 10760118272 1403859024 14.629 100 57 113 0 1446808800 29264448 13685328
20:55:44 8471 8192000000 10318735328 10733830144 1364545872 14.278 99 55 110 0 1410676112 31740752 14389488
20:56:57 6662 8192000000 10302396272 10712731648 1351652064 14.162 98 62 124 2 1404770000 37625104 15492832
20:58:08 9380 8192000000 10294451568 10713657344 1351762544 14.163 99 46 91 0 1389497920 26410288 11325088
20:59:25 7306 8192000000 10305996144 10729697280 1366532688 14.296 100 45 89 0 1410007200 32144384 11330128
21:00:49 8978 8192000000 10278236224 10702680064 1341541328 14.071 99 32 64 1 1375452976 26127344 7784304
21:02:30 38804 8192000000 10263150224 10692964352 1310081888 13.787 98 19 38 0 1347963296 32938448 4942960
21:04:10 21049 8192000000 10267257328 10696507392 1325264064 13.924 99 17 34 1 1362077952 31710048 5103840
21:05:38 34452 8192000000 10277217248 10716909568 1325680480 13.928 98 20 40 0 1360698784 28877488 6140816
21:07:14 16760 8192000000 10256858064 10682306560 1315813936 13.839 98 25 50 0 1335498752 13823360 5861456
21:08:33 34104 8192000000 10279752400 10702393344 1357758576 14.217 100 47 93 0 1385829520 17862640 10208304
21:10:09 133247 8192000000 10268305376 10721529856 1355599952 14.198 99 29 55 0 1376520048 14623664 6296432
21:13:24 37456 8192000000 10294923760 10735939584 1383959200 14.452 100 36 68 2 1404743456 13295280 7488976
21:15:06 49905 8192000000 10308687536 10761883648 1398712848 14.584 100 24 47 0 1420376512 16161424 5502240
21:17:33 76421 8192000000 10281021216 10741121024 1367702512 14.306 98 14 29 0 1382315232 11373120 3239600
21:19:50 76381 8192000000 10302027008 10760953856 1400962512 14.604 100 20 40 0 1418092176 12342096 4787568
21:22:13 74744 8192000000 10312242016 10762301440 1403481760 14.626 100 29 58 0 1423454112 11718224 8254128
21:24:31 81408 8192000000 10306221600 10745597952 1349619008 14.144 96 25 50 0 1366872384 11350352 5903024
21:26:53 18632 8192000000 10286262560 10741010432 1386855120 14.478 99 34 68 0 1402682832 7792912 8034800
21:28:18 58841 8192000000 10309543584 10762350592 1409609808 14.680 100 22 44 0 1423674272 9125440 4939024
21:30:26 43659 8192000000 10296893872 10765414400 1405119968 14.641 100 23 47 0 1422278640 12088880 5069792
21:32:15 64787 8192000000 10314320832 10771988480 1410180544 14.686 100 32 64 1 1426880992 9056160 7644288
21:34:28 29947 8192000000 10314699520 10780602368 1401056736 14.604 99 19 38 1 1422704096 17517008 4130352
21:37:54 61658 8192000000 10295155696 10752905216 1372772064 14.352 97 8 16 0 1381671760 7321376 1578320
21:40:02 56149 8192000000 10295275056 10746810368 1363090096 14.265 97 14 28 0 1376635920 10700384 2845440
21:42:04 47079 8192000000 10245135888 10701201408 1268749568 13.410 92 18 36 0 1293266960 19660176 4857216
21:43:54 102507 8192000000 10255468672 10714136576 1253104976 13.267 91 11 22 1 1269937984 14726720 2106288
21:46:41 67302 8192000000 10271803104 10728656896 1341139968 14.068 98 20 41 0 1363233568 17589248 4504352
21:48:52 44431 8192000000 10260836816 10715312128 1307315872 13.762 97 28 57 1 1329908848 16345952 6247024
21:50:40 42919 8192000000 10241822832 10665443328 1214594320 12.912 90 27 54 0 1227821024 7452480 5774224
21:52:23 68082 8192000000 10238914336 10660782080 1287087008 13.578 97 29 56 1 1299653120 5796512 6769600
21:54:42 6594 8192000000 10243134432 10679934976 1324944848 13.921 99 28 54 0 1341246352 9935584 6365920
21:55:52 65226 8192000000 10237917104 10679664640 1311039920 13.796 97 16 31 0 1329219440 14639776 3539744
21:58:05 59413 8192000000 10248894576 10693947392 1331611904 13.982 99 17 34 0 1355505072 19774976 4118192
22:00:06 68613 8192000000 10245435664 10709872640 1342671840 14.081 100 8 16 0 1358667552 14242000 1753712
22:02:26 46049 8192000000 10270381920 10708860928 1344236288 14.096 99 19 38 0 1355562448 7200976 4125184
22:04:13 22334 8192000000 10276628288 10714357760 1353199712 14.176 99 37 71 2 1369675856 8836768 7639376
22:05:35 52168 8192000000 10293532784 10732994560 1375234784 14.374 100 31 62 0 1395718176 13242208 7241184
22:07:28 70417 8192000000 10277349472 10732478464 1359350048 14.232 99 34 69 0 1381687504 14482096 7855360
22:09:39 165814 8192000000 10296941728 10754015232 1338572416 14.045 96 7 14 0 1351537648 11775696 1189536
22:13:30 111219 8192000000 10314953568 10754879488 1386099664 14.471 99 35 70 2 1405014288 10932896 7981728
22:16:25 76434 8192000000 10300518368 10764386304 1402586512 14.618 100 10 20 0 1416640624 11614416 2439696
22:18:42 51563 8192000000 10311073888 10766618624 1404546256 14.635 100 19 38 0 1420408592 11662208 4200128
22:20:50 57540 8192000000 10292861952 10760114176 1389282640 14.499 99 10 20 0 1400562016 9147792 2131584
22:22:49 41659 8192000000 10286935888 10742321152 1354004912 14.183 97 30 60 1 1374516272 13449472 7061888
22:24:31 75643 8192000000 10288066272 10738184192 1342736704 14.082 97 24 51 1 1359475072 10888832 5849536
22:26:48 58307 8192000000 10299275936 10748977152 1348167536 14.131 97 22 43 0 1365217088 12430816 4618736
22:28:47 74116 8192000000 10272482528 10725810176 1309315264 13.780 95 15 30 0 1323467984 10798688 3354032
22:31:04 37537 8192000000 10259936176 10697596928 1330969904 13.976 98 28 56 0 1351408256 14197408 6240944
22:32:42 20950 8192000000 10295201840 10730545152 1347334944 14.123 99 47 94 0 1375787936 18510048 9942944
22:34:13 90709 8192000000 10246695776 10699362304 1346033296 14.112 99 16 32 0 1360529744 10748688 3747760
22:36:45 66571 8192000000 10252399728 10703020032 1349412096 14.142 99 13 26 0 1369324176 17523168 2388912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 22-12-2022 22:41:18 to 22-12-2022 22:41:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 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 22-12-2022 22:41:35 to 22-12-2022 22:41:37.

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          242726
MON$OLDEST_ACTIVE               242727
MON$OLDEST_SNAPSHOT             242727
MON$NEXT_TRANSACTION            242727
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                       2022336
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:1054: 
:1055: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:47 2022
:1056:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1057: 
:1058: 
:1059: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:49 2022
:1060:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1061: 
:1062: 
:1063: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:52 2022
:1064:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1065: 
:1066: 
:1067: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:57 2022
:1068:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1069: 
:1070: 
:1071: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:57 2022
:1072:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1073: 
:1074: 
:1075: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:57 2022
:1076:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1077: 
:1078: 
:1079: mscs-test-161.budzdorov.ru    Thu Dec 22 22:38:58 2022
:1080:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1081: 
--- end of diff output ---

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

22.12.22 22:41:38. 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-12-22 22:41:40 No severe PSQL-related problems occured
22.12.22 22:41:42. End of report.