Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 01: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 16765316 kB
MemAvailable 31561820 kB
Buffers 0 kB
Cached 14985360 kB
SwapCached 2528 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.10
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-04-24 01:38:13
DTS_END                         2022-04-24 01:38:13.5390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54730
TRN_ID                          239872
ATT_ID                          644
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: 9201.35 26849 1104162
customer order: creation 168.83 388 20259
customer order: refuse 14.93 323 1792
order to supplier: creation 45.28 1242 5434
order to supplier: removal 3.53 95902 424
invoice (draft): creation 46.64 3127 5597
invoice (draft): removal 53.27 734 6392
invoice accept: apply 41.16 16700 4939
invoice accept: cancel 47.53 772 5704
customer reserve: creation 3822.28 164 458673
customer reserve: removal 4670.62 58 560474
realization accept: apply 82.38 175 9885
realization accept: cancel 76.97 113 9237
payment from customer: creation 57.65 121 6918
payment from customer: removal 5.49 90 659
payment to supplier: creation 48.78 136 5853
payment to supplier: removal 3.47 84 416
service: total inventory turnovers 5.17 1172 620
service: total monetary turnovers 6.58 28 789
service: aggregate perf. data 0.78 23514 93
service: refresh index statistics 0.03 392142 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3675
TEST_TIME 2 4327
TEST_TIME 3 3579
TEST_TIME 4 4026
TEST_TIME 5 4078
TEST_TIME 6 4024
TEST_TIME 7 3903
TEST_TIME 8 3872
TEST_TIME 9 4338
TEST_TIME 10 4832
TEST_TIME 11 4987
TEST_TIME 12 5397
TEST_TIME 13 5648
TEST_TIME 14 6914
TEST_TIME 15 7100
TEST_TIME 16 7829
TEST_TIME 17 8102
TEST_TIME 18 8314
TEST_TIME 19 8425
TEST_TIME 20 8270
TEST_TIME 21 8473
TEST_TIME 22 8790
TEST_TIME 23 8941
TEST_TIME 24 8889
TEST_TIME 25 9105
TEST_TIME 26 9519
TEST_TIME 27 10547
TEST_TIME 28 11021
TEST_TIME 29 10881
TEST_TIME 30 10998
TEST_TIME 31 10655
TEST_TIME 32 10491
TEST_TIME 33 10425
TEST_TIME 34 10364
TEST_TIME 35 10441
TEST_TIME 36 10253
TEST_TIME 37 10174
TEST_TIME 38 10185
TEST_TIME 39 10218
TEST_TIME 40 10153
TEST_TIME 41 10204
TEST_TIME 42 10131
TEST_TIME 43 9956
TEST_TIME 44 9917
TEST_TIME 45 9852
TEST_TIME 46 9820
TEST_TIME 47 9734
TEST_TIME 48 9629
TEST_TIME 49 9557
TEST_TIME 50 9574
TEST_TIME 51 9590
TEST_TIME 52 9655
TEST_TIME 53 9501
TEST_TIME 54 9569
TEST_TIME 55 9532
TEST_TIME 56 9389
TEST_TIME 57 9385
TEST_TIME 58 9378
TEST_TIME 59 9382
TEST_TIME 60 9367
TEST_TIME 61 9445
TEST_TIME 62 9403
TEST_TIME 63 9469
TEST_TIME 64 9455
TEST_TIME 65 9453
TEST_TIME 66 9572
TEST_TIME 67 9613
TEST_TIME 68 9531
TEST_TIME 69 9532
TEST_TIME 70 9590
TEST_TIME 71 9563
TEST_TIME 72 9626
TEST_TIME 73 9611
TEST_TIME 74 9499
TEST_TIME 75 9469
TEST_TIME 76 9425
TEST_TIME 77 9464
TEST_TIME 78 9408
TEST_TIME 79 9357
TEST_TIME 80 9339
TEST_TIME 81 9287
TEST_TIME 82 9266
TEST_TIME 83 9238
TEST_TIME 84 9247
TEST_TIME 85 9235
TEST_TIME 86 9308
TEST_TIME 87 9329
TEST_TIME 88 9317
TEST_TIME 89 9381
TEST_TIME 90 9396
TEST_TIME 91 9378
TEST_TIME 92 9426
TEST_TIME 93 9431
TEST_TIME 94 9469
TEST_TIME 95 9479
TEST_TIME 96 9487
TEST_TIME 97 9427
TEST_TIME 98 9394
TEST_TIME 99 9335
TEST_TIME 100 9324
TEST_TIME 101 9286
TEST_TIME 102 9262
TEST_TIME 103 9215
TEST_TIME 104 9244
TEST_TIME 105 9233
TEST_TIME 106 9281
TEST_TIME 107 9238
TEST_TIME 108 9232
TEST_TIME 109 9266
TEST_TIME 110 9289
TEST_TIME 111 9271
TEST_TIME 112 9322
TEST_TIME 113 9291
TEST_TIME 114 9312
TEST_TIME 115 9354
TEST_TIME 116 9368
TEST_TIME 117 9309
TEST_TIME 118 9293
TEST_TIME 119 9261
TEST_TIME 120 9204

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-04-2022 01:39:54 to 24-04-2022 01: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 1528621 1528621 0 0.00 0 44603 6 0 0
doc_list_biud 1528621 1528621 0 0.00 0 67301 27 0 0
sp_add_invoice_to_stock 5028 4939 89 1.77 29 140652 16700 0 89
sp_cancel_adding_invoice 5704 5704 0 0.00 4 72240 772 0 0
sp_cancel_client_order 1792 1792 0 0.00 5 8703 324 0 0
sp_cancel_customer_reserve 560474 560474 0 0.00 1 67303 58 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 13947 91 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 5510 84 0 0
sp_cancel_supplier_invoice 6395 6392 3 0.04 2 17206 735 0 3
sp_cancel_supplier_order 424 424 0 0.00 6 1112862 95902 0 0
sp_cancel_write_off 9237 9237 0 0.00 1 21296 114 0 0
sp_client_order 20259 20259 0 0.00 3 17955 389 0 0
sp_customer_reserve 458673 458673 0 0.00 0 43743 164 0 0
sp_fill_shopping_cart 31284 31284 0 0.00 1 20323 42 0 0
sp_fill_shopping_cart_clo_res 458668 458668 0 0.00 0 18519 16 0 0
sp_get_clo_for_invoice 4934 4934 0 0.00 0 21748 379 0 0
sp_kill_cost_storno 16016 16016 0 0.00 0 22832 61 0 0
sp_kill_qstorno_ret_qs2qd 1137001 1137001 0 0.00 0 67300 31 0 0
sp_kill_qty_storno 598840 598840 0 0.00 0 67301 68 0 0
sp_lock_selected_doc 20562 20562 0 0.00 0 11752 6 0 0
sp_make_cost_storno 27588 27588 0 0.00 0 23017 52 0 0
sp_multiply_rows_for_pdistr 27588 27588 0 0.00 0 20107 31 0 0
sp_multiply_rows_for_qdistr 20258 20258 0 0.00 0 16892 184 0 0
sp_pay_from_customer 6918 6918 0 0.00 4 21137 121 0 0
sp_pay_to_supplier 5853 5853 0 0.00 5 18516 136 0 0
sp_payment_common 12771 12771 0 0.00 1 21105 39 0 0
sp_qd_handle_on_cancel_clo 1792 1792 0 0.00 0 7446 294 0 0
sp_qd_handle_on_invoice_upd_sts 10636 10636 0 0.00 0 21301 315 0 0
sp_qd_handle_on_reserve_upd_sts 9885 9885 0 0.00 0 10305 58 0 0
sp_reserve_write_off 9885 9885 0 0.00 6 20199 176 0 0
sp_supplier_invoice 5597 5597 0 0.00 6 85637 3127 0 0
sp_supplier_order 5434 5434 0 0.00 5 43907 1242 0 0
srv_aggregate_perf_data 93 93 0 0.00 2332 228547 23515 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2256 137555 49468 0 0
srv_find_qd_qs_mism 1056811 1056811 0 0.00 0 44586 5 0 0
srv_make_invnt_saldo 620 620 0 0.00 36 80144 1173 0 0
srv_make_money_saldo 789 789 0 0.00 1 3411 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 206187 651517 392142 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4939 4939 0 0.00 0 11106 141 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 3910 91 0 0
t$perf-norm:sp_cancel_client_order 1792 1792 0 0.00 0 2692 8 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 993 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 6895 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 2314 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1198 13 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 21090 1450 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2636 5 0 0
t$perf-norm:sp_client_order 20260 20260 0 0.00 0 38512 23 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 4216 14 0 0
t$perf-norm:sp_pay_from_customer 6918 6918 0 0.00 0 5829 7 0 0
t$perf-norm:sp_pay_to_supplier 5853 5853 0 0.00 0 6565 11 0 0
t$perf-norm:sp_reserve_write_off 9885 9885 0 0.00 0 5621 7 0 0
t$perf-norm:sp_supplier_invoice 5597 5597 0 0.00 0 4149 15 0 0
t$perf-norm:sp_supplier_order 5434 5434 0 0.00 0 7100 16 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 509 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 620 620 0 0.00 0 4073 22 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 783 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10042 9864 178 1.77 0 6224 18 0 178
v_all_customers 36956 36956 0 0.00 0 12 0 0 0
v_all_suppliers 30762 30762 0 0.00 0 18 1 0 0
v_all_wares 40516 40516 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1813 8 0 0
v_cancel_client_order 3584 3584 0 0.00 0 6721 7 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 469 3 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 97 2 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 4687 11 0 0
v_cancel_supplier_invoice 774 768 6 0.77 0 300 11 0 6
v_cancel_supplier_order 848 848 0 0.00 0 3035 9 0 0
v_cancel_write_off 2022 2022 0 0.00 0 3188 12 0 0
v_max_id_clo_ord 5432 5432 0 0.00 0 317 1 0 0
v_max_id_clo_res 993 993 0 0.00 0 6985 29 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 5610 5 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 323 2 0 0
v_max_non_paid_realizn 3707 3707 0 0.00 0 2742 4 0 0
v_min_id_clo_ord 5432 5432 0 0.00 0 13294 20 0 0
v_min_id_clo_res 993 993 0 0.00 0 9114 17 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 7686 7 0 0
v_min_non_paid_invoice 5853 5853 0 0.00 0 18117 29 0 0
v_min_non_paid_realizn 6918 6918 0 0.00 0 20780 36 0 0
v_our_firm 9982 9982 0 0.00 0 16 1 0 0
v_reserve_write_off 19770 19770 0 0.00 0 4695 6 0 0
x_lock_depdocs_on_canc_invoice 11712 11712 0 0.00 0 9327 102 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 13403 407 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 2 43896 1150 0 0
x_make_qstorno_1200_2000 5597 5597 0 0.00 3 82146 2986 0 0
x_make_qstorno_2100_3300 458673 458673 0 0.00 0 43743 146 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 01:39:58 to 24-04-2022 01: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
23:08:17 13 8192000000 8808241152 8822624256 13170352 0.160 4 2 3 0 13317488 72176 74960
23:09:17 14631 8192000000 9886159696 10194538496 967885552 10.566 100 86 172 0 996327440 5427632 23014256
23:10:36 27374 8192000000 9939159536 10260709376 1023503616 11.106 100 89 178 0 1053842752 7492336 22846800
23:12:08 25787 8192000000 9971449632 10305966080 1056955312 11.427 100 83 166 2 1087583616 9651456 20976848
23:13:40 14353 8192000000 10036208624 10386432000 1125147696 12.076 100 74 149 0 1152816816 10243728 17425392
23:14:56 6739 8192000000 10091124848 10456186880 1180912304 12.599 100 75 150 0 1210133808 11772032 17449472
23:16:05 5622 8192000000 10137089552 10507067392 1224885888 13.007 100 80 160 4 1255845792 13083248 17876656
23:17:12 7332 8192000000 10160153168 10540949504 1253158112 13.267 100 69 138 1 1281243424 13208592 14876720
23:18:22 1622 8192000000 10203093152 10582622208 1284633232 13.555 100 93 184 1 1320307488 14361056 21313200
23:19:25 1495 8192000000 10226061760 10611695616 1306072416 13.750 100 95 191 0 1340360624 12813200 21475008
23:20:29 5644 8192000000 10206886448 10611339264 1307893952 13.767 100 39 76 0 1324832272 8228224 8710096
23:21:37 6074 8192000000 10206850928 10627170304 1321795200 13.893 100 19 38 1 1333058752 6997456 4266096
23:22:43 9620 8192000000 10211450096 10632458240 1326888256 13.939 100 20 40 1 1337036480 5932000 4216224
23:23:53 12026 8192000000 10225467584 10644393984 1334528592 14.008 100 19 38 2 1344599504 5617744 4453168
23:25:08 16214 8192000000 10230241088 10652516352 1338000544 14.039 100 29 58 2 1350554352 5432672 7121136
23:26:25 6328 8192000000 10232678112 10657460224 1343480352 14.089 100 25 49 0 1355575280 5632736 6462192
23:27:35 9154 8192000000 10203277504 10620084224 1304568656 13.737 98 19 38 0 1314548928 5916352 4063920
23:28:48 29116 8192000000 10171845424 10588090368 1253929904 13.274 96 35 71 1 1268621168 5829104 8862160
23:30:41 5264 8192000000 10155277168 10548801536 1230078544 13.055 97 36 69 2 1242640976 5486640 7075792
23:31:47 10259 8192000000 10146030384 10545618944 1225615392 13.014 98 15 29 2 1233811696 4590832 3605472
23:32:58 8177 8192000000 10159815104 10556809216 1247413952 13.214 99 36 72 1 1261800704 5541328 8845424
23:34:13 54550 8192000000 10182138976 10590773248 1284351248 13.553 100 39 74 0 1298591696 5470032 8770416
23:36:10 5652 8192000000 10197727888 10601766912 1294672320 13.647 100 33 66 1 1307815120 5166720 7976080
23:37:19 8872 8192000000 10199110288 10613182464 1306200944 13.752 100 20 40 0 1316833712 6257328 4375440
23:38:32 36062 8192000000 10233794672 10646372352 1330089584 13.968 100 45 90 0 1347138656 6290368 10758704
23:40:18 14791 8192000000 10259741648 10675077120 1353776976 14.181 100 33 66 1 1373247952 11615040 7855936
23:41:40 137554 8192000000 10286687648 10719789056 1373740000 14.361 100 37 72 0 1397903008 15946112 8216896
23:45:03 18428 8192000000 10312447216 10738331648 1398322224 14.580 100 45 90 2 1426357264 17413600 10621440
23:46:39 10312 8192000000 10337349360 10763735040 1416805232 14.744 100 48 96 2 1452313536 23732400 11775904
23:47:59 4822 8192000000 10349967296 10773549056 1412516800 14.706 100 64 127 1 1458153872 30562960 15074112
23:49:09 7804 8192000000 10368954192 10786660352 1421213504 14.783 100 73 146 2 1471167360 32173440 17780416
23:50:20 4743 8192000000 10353878624 10784489472 1422917696 14.799 100 54 107 0 1468558880 32262016 13379168
23:51:31 3147 8192000000 10361176928 10778886144 1409313664 14.678 99 78 157 2 1466342128 36543600 20484864
23:52:39 3264 8192000000 10380666688 10800365568 1424820304 14.815 100 81 162 1 1488089232 43094016 20174912
23:53:51 3938 8192000000 10366409184 10786082816 1414376016 14.723 100 79 158 1 1470011664 36237088 19398560
23:54:57 4624 8192000000 10309215424 10726793216 1354964432 14.192 97 51 102 3 1398765088 30446992 13353664
23:56:08 6343 8192000000 10304339376 10724757504 1354039008 14.184 99 50 98 2 1402298048 35926656 12332384
23:57:16 15992 8192000000 10314201008 10743652352 1370812576 14.334 99 27 52 2 1413538016 35839920 6885520
23:58:34 13013 8192000000 10313303152 10735583232 1344371040 14.097 98 44 88 0 1402659536 46673120 11615376
23:59:50 30771 8192000000 10319527344 10751361024 1357057696 14.211 98 33 66 1 1414942432 48951936 8932800
00:01:23 12356 8192000000 10292127664 10721075200 1337806032 14.038 99 34 68 0 1390955968 43722064 9427872
00:02:55 22427 8192000000 10296627824 10730217472 1343549744 14.089 100 20 40 0 1402517200 52804800 6162656
00:04:28 23322 8192000000 10298724704 10735071232 1340355184 14.061 99 27 54 0 1398261936 49833808 8072944
00:05:53 31190 8192000000 10265289840 10716246016 1328906640 13.957 98 14 28 0 1357476928 24961216 3609072
00:07:25 75921 8192000000 10282160704 10743824384 1363811280 14.272 100 13 26 0 1389198672 21638336 3749056
00:09:53 91631 8192000000 10300588944 10741571584 1384792368 14.459 100 24 48 1 1400923504 10635488 5495648
00:12:32 87311 8192000000 10289366224 10750418944 1350600544 14.153 97 19 38 1 1375192336 20013872 4577920
00:15:10 81525 8192000000 10302754112 10763837440 1384478544 14.457 99 13 26 0 1404066064 16573776 3013744
00:17:33 59347 8192000000 10274474496 10725879808 1320975632 13.886 95 16 32 0 1337586416 12823136 3787648
00:19:50 98661 8192000000 10291845744 10742677504 1384220288 14.454 100 14 28 0 1399874352 12574624 3079440
00:22:31 53681 8192000000 10299788208 10753957888 1399162560 14.588 100 40 80 2 1417572432 8487424 9922448
00:24:30 53242 8192000000 10306141568 10763980800 1404575904 14.636 100 19 38 2 1420288160 10943952 4768304
00:26:27 2256 8192000000 10318197168 10760421376 1396591104 14.565 99 50 100 2 1420906288 12477232 11837952
00:27:32 54137 8192000000 10328403840 10775355392 1398692320 14.583 99 43 87 2 1427637856 18657504 10288032
00:29:46 40117 8192000000 10307895248 10786250752 1394394752 14.545 99 9 18 3 1415776816 18856736 2525328
00:31:27 57097 8192000000 10288038080 10754043904 1371296016 14.339 98 18 36 0 1381895408 6091072 4508320
00:33:24 79701 8192000000 10287231184 10763464704 1394344192 14.545 100 3 6 0 1406124736 11257648 522896
00:35:45 60274 8192000000 10303329632 10771173376 1405730128 14.646 100 15 28 2 1417695296 8808448 3156720
00:37:45 89143 8192000000 10316580000 10780344320 1412795680 14.709 100 23 46 0 1428619488 10678352 5145456
00:40:29 59933 8192000000 10307482608 10766954496 1395047360 14.551 99 21 42 0 1410608560 10765136 4796064
00:42:31 44738 8192000000 10310818560 10770165760 1399114768 14.587 99 23 46 0 1413461136 9554496 4791872
00:44:21 86642 8192000000 10297049072 10757066752 1312623216 13.810 93 19 38 0 1332880480 16208864 4048400
00:46:49 35143 8192000000 10284838160 10731679744 1327162208 13.942 95 37 73 1 1347745600 12385584 8197808
00:48:24 41991 8192000000 10285728480 10713325568 1333393312 13.998 97 27 54 0 1348212384 9288464 5530608
00:50:07 74820 8192000000 10230585344 10663710720 1246696640 13.208 91 26 52 1 1265999984 13907632 5395712
00:52:33 122538 8192000000 10234811072 10676080640 1277909584 13.494 95 27 54 0 1294557536 10334592 6313360
00:55:38 85115 8192000000 10239367824 10701787136 1321317440 13.889 98 7 14 0 1337011920 14329504 1364976
00:58:47 69973 8192000000 10240254144 10693206016 1317793104 13.857 97 13 26 0 1330430176 9692832 2944240
01:00:57 39830 8192000000 10251918832 10695643136 1320463520 13.881 98 21 41 1 1332937536 8230640 4243376
01:02:38 61925 8192000000 10252696736 10704769024 1324497728 13.917 98 18 36 0 1342224720 13607520 4119472
01:04:44 42842 8192000000 10245380288 10695401472 1321522176 13.890 98 14 28 1 1342977664 17910992 3544496
01:06:39 62583 8192000000 10258383104 10716499968 1336543728 14.026 98 20 40 2 1356913696 15703824 4666144
01:08:45 23413 8192000000 10262761520 10693083136 1332109696 13.986 98 35 67 0 1347112480 8053504 6949280
01:10:09 95075 8192000000 10281119424 10726322176 1334951136 14.012 97 15 30 0 1353842176 14500768 4390272
01:12:45 101991 8192000000 10285721056 10755878912 1382640880 14.440 100 18 36 0 1403473744 16374336 4458528
01:16:28 51381 8192000000 10291937440 10747936768 1377378944 14.393 99 33 65 0 1395974176 11742912 6852320
01:18:22 73079 8192000000 10290838160 10759929856 1392785344 14.531 100 10 20 1 1403646896 8637888 2223664
01:20:43 44131 8192000000 10285023984 10744131584 1380295296 14.419 99 15 30 0 1391837600 8035760 3506544
01:22:34 35233 8192000000 10303088480 10761805824 1398210864 14.579 100 15 30 0 1409381584 8119744 3050976
01:24:20 95008 8192000000 10303358528 10755854336 1378371616 14.402 98 26 52 0 1393831920 9487488 5972816
01:27:05 25028 8192000000 10307053920 10750128128 1379469328 14.412 99 22 43 1 1392834720 7978256 5387136
01:28:32 35265 8192000000 10293255440 10735169536 1375976512 14.381 99 22 44 0 1390180512 9146064 5057936
01:30:13 75737 8192000000 10271669152 10718040064 1316399216 13.844 95 20 40 1 1335895808 15153744 4342848
01:32:32 42666 8192000000 10277356032 10702204928 1330267920 13.970 98 31 62 0 1349018944 12029904 6721120
01:34:19 102889 8192000000 10237528208 10686337024 1273134800 13.450 94 15 30 0 1289592928 13122848 3335280
01:37:02 49053 8192000000 10266645472 10714095616 1351551968 14.161 99 30 60 0 1367567664 9654416 6361280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-04-2022 01:40:04 to 24-04-2022 01:40:19.

Exceptions occured during test was in run

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

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

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          239338
MON$OLDEST_ACTIVE               240368
MON$OLDEST_SNAPSHOT             240368
MON$NEXT_TRANSACTION            240368
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                       2015552
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1543: 
:1544: mscs-test-161.budzdorov.ru    Sun Apr 24 01:38:21 2022
:1545:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1546: 
:1547: 
:1548: mscs-test-161.budzdorov.ru    Sun Apr 24 01:38:21 2022
:1549:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1550: 
:1551: 
:1552: mscs-test-161.budzdorov.ru    Sun Apr 24 01:38:21 2022
:1553:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1554: 
:1555: 
:1556: mscs-test-161.budzdorov.ru    Sun Apr 24 01:38:21 2022
:1557:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1558: 
:1559: 
:1560: mscs-test-161.budzdorov.ru    Sun Apr 24 01:38:21 2022
:1561:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1562: 
--- end of diff output ---

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

24.04.22 01:40:23. 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-04-24 01:40:25 No severe PSQL-related problems occured
24.04.22 01:40:26. End of report.