Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.06.2022 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16032120 kB
MemAvailable 31169036 kB
Buffers 0 kB
Cached 15650276 kB
SwapCached 2492 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-06-27 22:38:21
DTS_END                         2022-06-27 22:38:21.0330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57878
TRN_ID                          244190
ATT_ID                          621
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: 9517.62 18350 1142114
customer order: creation 177.97 349 21356
customer order: refuse 15.72 338 1886
order to supplier: creation 46.41 1323 5569
order to supplier: removal 3.62 86257 434
invoice (draft): creation 47.28 3718 5674
invoice (draft): removal 54.46 713 6535
invoice accept: apply 42.17 16596 5060
invoice accept: cancel 48.48 763 5818
customer reserve: creation 3947.02 162 473642
customer reserve: removal 4831.83 49 579819
realization accept: apply 84.63 178 10156
realization accept: cancel 86.90 97 10428
payment from customer: creation 59.91 107 7189
payment from customer: removal 5.92 72 710
payment to supplier: creation 50.03 127 6004
payment to supplier: removal 3.32 61 398
service: total inventory turnovers 4.61 1057 553
service: total monetary turnovers 6.48 22 778
service: aggregate perf. data 0.84 20053 101
service: refresh index statistics 0.03 234963 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-06-2022 22:39:19 to 27-06-2022 22:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2277
TEST_TIME 2 3510
TEST_TIME 3 3164
TEST_TIME 4 4296
TEST_TIME 5 4182
TEST_TIME 6 4316
TEST_TIME 7 4523
TEST_TIME 8 4594
TEST_TIME 9 4844
TEST_TIME 10 4959
TEST_TIME 11 5429
TEST_TIME 12 5726
TEST_TIME 13 6067
TEST_TIME 14 6664
TEST_TIME 15 7010
TEST_TIME 16 7948
TEST_TIME 17 8145
TEST_TIME 18 8227
TEST_TIME 19 8300
TEST_TIME 20 8661
TEST_TIME 21 9169
TEST_TIME 22 9165
TEST_TIME 23 9234
TEST_TIME 24 9394
TEST_TIME 25 9497
TEST_TIME 26 9492
TEST_TIME 27 9496
TEST_TIME 28 9952
TEST_TIME 29 10319
TEST_TIME 30 10124
TEST_TIME 31 10191
TEST_TIME 32 10232
TEST_TIME 33 10266
TEST_TIME 34 10180
TEST_TIME 35 9957
TEST_TIME 36 10188
TEST_TIME 37 10119
TEST_TIME 38 10050
TEST_TIME 39 10197
TEST_TIME 40 10193
TEST_TIME 41 10118
TEST_TIME 42 10186
TEST_TIME 43 9964
TEST_TIME 44 9826
TEST_TIME 45 9729
TEST_TIME 46 9760
TEST_TIME 47 9834
TEST_TIME 48 9779
TEST_TIME 49 9764
TEST_TIME 50 9753
TEST_TIME 51 9803
TEST_TIME 52 9700
TEST_TIME 53 9678
TEST_TIME 54 9621
TEST_TIME 55 9572
TEST_TIME 56 9711
TEST_TIME 57 9628
TEST_TIME 58 9516
TEST_TIME 59 9508
TEST_TIME 60 9466
TEST_TIME 61 9379
TEST_TIME 62 9358
TEST_TIME 63 9377
TEST_TIME 64 9397
TEST_TIME 65 9429
TEST_TIME 66 9553
TEST_TIME 67 9573
TEST_TIME 68 9598
TEST_TIME 69 9557
TEST_TIME 70 9589
TEST_TIME 71 9695
TEST_TIME 72 9749
TEST_TIME 73 9741
TEST_TIME 74 9700
TEST_TIME 75 9767
TEST_TIME 76 9740
TEST_TIME 77 9654
TEST_TIME 78 9595
TEST_TIME 79 9585
TEST_TIME 80 9591
TEST_TIME 81 9582
TEST_TIME 82 9543
TEST_TIME 83 9536
TEST_TIME 84 9522
TEST_TIME 85 9491
TEST_TIME 86 9503
TEST_TIME 87 9486
TEST_TIME 88 9457
TEST_TIME 89 9431
TEST_TIME 90 9428
TEST_TIME 91 9511
TEST_TIME 92 9498
TEST_TIME 93 9540
TEST_TIME 94 9575
TEST_TIME 95 9536
TEST_TIME 96 9612
TEST_TIME 97 9614
TEST_TIME 98 9631
TEST_TIME 99 9696
TEST_TIME 100 9701
TEST_TIME 101 9734
TEST_TIME 102 9715
TEST_TIME 103 9638
TEST_TIME 104 9628
TEST_TIME 105 9605
TEST_TIME 106 9563
TEST_TIME 107 9564
TEST_TIME 108 9535
TEST_TIME 109 9557
TEST_TIME 110 9558
TEST_TIME 111 9495
TEST_TIME 112 9422
TEST_TIME 113 9470
TEST_TIME 114 9476
TEST_TIME 115 9572
TEST_TIME 116 9543
TEST_TIME 117 9547
TEST_TIME 118 9584
TEST_TIME 119 9563
TEST_TIME 120 9518

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-06-2022 22:39:22 to 27-06-2022 22:39:26.

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 1580356 1580356 0 0.00 0 17377 6 0 0
doc_list_biud 1580355 1580355 0 0.00 0 29151 24 0 0
sp_add_invoice_to_stock 5155 5060 95 1.84 43 150227 16596 0 95
sp_cancel_adding_invoice 5818 5818 0 0.00 5 58444 764 0 0
sp_cancel_client_order 1886 1886 0 0.00 4 20180 339 0 0
sp_cancel_customer_reserve 579819 579819 0 0.00 1 29153 50 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 5135 73 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2288 62 0 0
sp_cancel_supplier_invoice 6537 6535 2 0.03 2 13939 713 0 2
sp_cancel_supplier_order 434 434 0 0.00 7 880474 86257 0 0
sp_cancel_write_off 10428 10428 0 0.00 1 9444 98 0 0
sp_client_order 21356 21356 0 0.00 3 32319 349 0 0
sp_customer_reserve 473642 473642 0 0.00 0 37874 162 0 0
sp_fill_shopping_cart 32597 32597 0 0.00 1 18164 35 0 0
sp_fill_shopping_cart_clo_res 473640 473640 0 0.00 0 22065 13 0 0
sp_get_clo_for_invoice 5058 5058 0 0.00 0 23352 262 0 0
sp_kill_cost_storno 17354 17354 0 0.00 0 17373 58 0 0
sp_kill_qstorno_ret_qs2qd 1177035 1177035 0 0.00 0 29149 27 0 0
sp_kill_qty_storno 620134 620134 0 0.00 0 29150 60 0 0
sp_lock_selected_doc 21195 21195 0 0.00 0 9399 7 0 0
sp_make_cost_storno 28407 28407 0 0.00 0 12821 54 0 0
sp_multiply_rows_for_pdistr 28407 28407 0 0.00 0 10499 28 0 0
sp_multiply_rows_for_qdistr 21356 21356 0 0.00 0 15281 145 0 0
sp_pay_from_customer 7189 7189 0 0.00 4 10543 108 0 0
sp_pay_to_supplier 6004 6004 0 0.00 4 10286 127 0 0
sp_payment_common 13193 13193 0 0.00 2 7602 33 0 0
sp_qd_handle_on_cancel_clo 1886 1886 0 0.00 0 20155 310 0 0
sp_qd_handle_on_invoice_upd_sts 10876 10876 0 0.00 0 22857 317 0 0
sp_qd_handle_on_reserve_upd_sts 10156 10156 0 0.00 0 7715 55 0 0
sp_reserve_write_off 10156 10156 0 0.00 6 16273 178 0 0
sp_supplier_invoice 5674 5674 0 0.00 6 73667 3719 0 0
sp_supplier_order 5569 5569 0 0.00 5 28116 1324 0 0
srv_aggregate_perf_data 101 101 0 0.00 3381 208942 20053 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 1339 118616 39124 0 0
srv_find_qd_qs_mism 1093096 1093096 0 0.00 0 13852 4 0 0
srv_make_invnt_saldo 553 553 0 0.00 48 51047 1058 0 0
srv_make_money_saldo 778 778 0 0.00 0 613 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 153688 353273 234963 0 0
t$perf-abend:sp_add_invoice_to_stock 95 95 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5060 5060 0 0.00 0 10500 153 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 2985 81 0 0
t$perf-norm:sp_cancel_client_order 1886 1886 0 0.00 0 3089 13 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1332 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 1462 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 872 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 431 431 0 0.00 0 528 12 0 0
t$perf-norm:sp_cancel_supplier_order 434 434 0 0.00 0 24021 1483 0 0
t$perf-norm:sp_cancel_write_off 1009 1009 0 0.00 0 2335 8 0 0
t$perf-norm:sp_client_order 21356 21356 0 0.00 0 9907 19 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 1569 7 0 0
t$perf-norm:sp_pay_from_customer 7189 7189 0 0.00 0 9244 11 0 0
t$perf-norm:sp_pay_to_supplier 6004 6004 0 0.00 0 5222 10 0 0
t$perf-norm:sp_reserve_write_off 10156 10156 0 0.00 0 6095 10 0 0
t$perf-norm:sp_supplier_invoice 5674 5674 0 0.00 0 8609 19 0 0
t$perf-norm:sp_supplier_order 5569 5569 0 0.00 0 16208 27 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 414 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 553 553 0 0.00 0 647 13 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 996 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10306 10116 190 1.84 0 5432 19 0 190
v_all_customers 38876 38876 0 0.00 0 14 1 0 0
v_all_suppliers 31564 31564 0 0.00 0 73 1 0 0
v_all_wares 42710 42710 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 3814 12 0 0
v_cancel_client_order 3772 3772 0 0.00 0 1294 4 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2468 5 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 2008 6 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1113 5 0 0
v_cancel_supplier_invoice 866 862 4 0.46 0 3946 19 0 4
v_cancel_supplier_order 868 868 0 0.00 0 3898 12 0 0
v_cancel_write_off 2018 2018 0 0.00 0 2643 15 0 0
v_max_id_clo_ord 5569 5569 0 0.00 0 148 1 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 2808 15 0 0
v_max_id_ord_sup 5673 5673 0 0.00 0 1553 3 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 554 3 0 0
v_max_non_paid_realizn 3785 3785 0 0.00 0 3678 4 0 0
v_min_id_clo_ord 5569 5569 0 0.00 0 10708 11 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 4051 10 0 0
v_min_id_ord_sup 5673 5673 0 0.00 0 6711 10 0 0
v_min_non_paid_invoice 6004 6004 0 0.00 0 6897 22 0 0
v_min_non_paid_realizn 7189 7189 0 0.00 0 7154 27 0 0
v_our_firm 10640 10640 0 0.00 0 14 1 0 0
v_reserve_write_off 20312 20312 0 0.00 0 6044 7 0 0
x_lock_depdocs_on_canc_invoice 11922 11922 0 0.00 0 27882 102 0 0
x_lock_depdocs_on_canc_sup_ord 434 434 0 0.00 0 5280 275 0 0
x_make_qstorno_1000_1200 5569 5569 0 0.00 2 28058 1245 0 0
x_make_qstorno_1200_2000 5674 5674 0 0.00 2 64601 3595 0 0
x_make_qstorno_2100_3300 473642 473642 0 0.00 0 37872 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-06-2022 22:39:27 to 27-06-2022 22:39:31.

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:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:25 26150 8192000000 9905900192 10216247296 987027648 10.753 100 88 176 0 1016493104 6200688 23264768
20:10:58 26930 8192000000 9953091520 10274668544 1033844992 11.205 100 91 183 0 1065224032 8179296 23199744
20:12:29 19705 8192000000 9995041504 10327527424 1073853152 11.589 100 88 176 0 1105452704 9819472 21780080
20:13:59 14681 8192000000 10048908736 10398478336 1126360640 12.087 100 81 162 0 1156535584 11049728 19125216
20:15:19 8963 8192000000 10097816000 10460139520 1183771744 12.625 100 68 135 0 1209616672 11185216 14659712
20:16:32 4996 8192000000 10135838832 10507530240 1226536928 13.022 100 76 152 2 1256279472 12641296 17101248
20:17:38 6459 8192000000 10175247936 10552406016 1260270736 13.332 100 76 150 1 1289299168 12742576 16285856
20:18:46 1668 8192000000 10203230688 10587246592 1293178448 13.633 100 92 184 1 1327880560 14186208 20515904
20:19:49 1431 8192000000 10227677136 10612625408 1304955312 13.740 100 95 189 2 1341351904 14494736 21901856
20:20:51 8722 8192000000 10205032256 10612600832 1311375376 13.799 100 39 78 0 1328135984 7711584 9049024
20:22:02 9746 8192000000 10211021200 10626461696 1323634816 13.910 100 17 30 2 1332101824 5162592 3304416
20:23:12 15674 8192000000 10212944640 10631962624 1327023504 13.940 100 10 20 0 1334527984 5342288 2162192
20:24:33 14736 8192000000 10216729744 10638110720 1338228640 14.041 100 30 61 2 1351534944 5704688 7601616
20:25:50 2228 8192000000 10255431008 10662535168 1339279744 14.051 100 90 184 0 1367888080 6707408 21900928
20:26:52 7621 8192000000 10228264256 10651320320 1337984816 14.039 100 25 51 0 1349278000 5000944 6292240
20:28:04 8927 8192000000 10233045264 10646122496 1312434496 13.808 98 29 58 0 1325353456 5901472 7017488
20:29:13 9348 8192000000 10175362000 10583216128 1259876384 13.329 97 13 26 1 1268361024 5517616 2967024
20:30:23 35745 8192000000 10127831968 10523533312 1085482208 11.700 84 23 46 0 1095275264 4314944 5478112
20:32:02 5988 8192000000 10123774128 10518024192 1201813120 12.793 97 28 53 3 1214415632 5649584 6952928
20:33:09 9035 8192000000 10161900560 10569904128 1269496032 13.417 100 13 26 0 1278011104 5574496 2940576
20:34:18 66640 8192000000 10177164272 10587123712 1286386704 13.571 100 40 80 0 1301623776 5382080 9854992
20:36:25 10058 8192000000 10181666656 10601906176 1298901616 13.685 100 25 50 0 1311130352 6933936 5294800
20:37:36 10653 8192000000 10197026960 10613440512 1306661424 13.756 100 13 24 0 1314234672 4968272 2604976
20:38:53 7748 8192000000 10244805168 10651996160 1336659680 14.027 100 52 104 1 1355078400 5832368 12586352
20:40:07 20893 8192000000 10261971072 10669895680 1353331728 14.177 100 42 82 1 1373991600 11399216 9260656
20:41:40 45028 8192000000 10275899536 10700292096 1363785584 14.271 100 45 90 1 1391673856 16711472 11176800
20:43:29 19918 8192000000 10287209968 10708905984 1371872576 14.344 100 37 73 0 1395799600 15386544 8540480
20:44:56 16536 8192000000 10300207184 10726666240 1380935424 14.425 100 46 92 1 1413400272 20645152 11819696
20:46:17 8261 8192000000 10313181504 10738180096 1394927680 14.550 100 45 89 1 1422958656 16774016 11256960
20:47:29 18181 8192000000 10325109088 10749214720 1401607776 14.609 100 53 103 3 1435524272 20618240 13298256
20:48:52 5889 8192000000 10338355936 10760953856 1409908352 14.683 100 62 124 0 1449262656 24239232 15115072
20:50:04 6849 8192000000 10335062064 10760867840 1406290912 14.651 100 52 102 3 1443793664 24620288 12882464
20:51:16 1338 8192000000 10354336416 10769494016 1408036544 14.666 100 66 130 2 1453136384 28546352 16553488
20:52:21 5952 8192000000 10353219392 10774216704 1401924160 14.612 100 70 140 1 1455206416 35410720 17871536
20:53:34 2607 8192000000 10339674496 10745749504 1383993280 14.452 100 90 178 1 1440118576 34321440 21803856
20:54:42 9090 8192000000 10302636272 10730946560 1376065568 14.381 100 36 72 1 1411028816 25708896 9254352
20:55:58 11375 8192000000 10289523504 10724634624 1361715408 14.253 99 24 48 1 1389606288 21392848 6498032
20:57:18 13828 8192000000 10312096528 10731347968 1374388256 14.366 100 53 106 0 1413908320 26938256 12581808
20:58:36 7279 8192000000 10302424880 10720661504 1363974816 14.273 100 51 102 3 1404949424 28171904 12802704
20:59:45 20236 8192000000 10280937168 10711670784 1349828160 14.146 100 24 47 2 1385944288 29658128 6458000
21:01:09 27641 8192000000 10301267824 10737008640 1367810416 14.307 100 24 48 1 1405730960 31588336 6332208
21:02:42 11852 8192000000 10302952224 10737577984 1368540608 14.314 100 23 46 2 1412362704 37214048 6608048
21:04:03 7745 8192000000 10293251744 10724085760 1347545936 14.125 99 23 44 1 1388704080 34465856 6692288
21:05:12 23051 8192000000 10274663984 10706440192 1345217792 14.104 99 22 44 1 1379419968 28093648 6108528
21:06:36 29956 8192000000 10261709440 10708725760 1328684752 13.955 99 14 28 2 1357485152 25378048 3422352
21:08:08 27574 8192000000 10274044752 10697269248 1332171120 13.987 98 35 65 0 1348714512 9624400 6918992
21:09:36 52184 8192000000 10264939920 10693656576 1319720144 13.874 98 14 28 0 1339640544 16797072 3123328
21:11:47 65479 8192000000 10275341344 10711703552 1351358208 14.160 99 10 20 0 1360139568 6646640 2134720
21:13:53 7692 8192000000 10307256400 10727698432 1361707664 14.253 99 60 118 2 1387220560 11195792 14317104
21:15:04 118615 8192000000 10303030656 10751520768 1380571264 14.422 100 9 18 0 1401959856 19205712 2182880
21:18:03 66387 8192000000 10321476768 10758955008 1393569072 14.538 100 11 22 0 1408166880 12133552 2464256
21:20:12 106301 8192000000 10312670608 10763644928 1390106480 14.507 99 19 38 2 1415928320 20841936 4979904
21:23:32 88297 8192000000 10322396816 10774872064 1406465360 14.653 100 18 36 1 1431685904 21043312 4177232
21:26:06 39959 8192000000 10326874128 10777632768 1403952288 14.630 99 35 71 0 1421022768 7990976 9079504
21:27:48 8302 8192000000 10350618432 10796302336 1427005616 14.835 100 67 135 0 1450378624 7257952 16115056
21:28:58 39907 8192000000 10313203392 10781995008 1401730528 14.610 99 17 35 0 1415513792 9172832 4610432
21:30:45 27377 8192000000 10311583808 10772873216 1399048976 14.587 99 14 28 0 1416443120 14276768 3117376
21:32:13 37397 8192000000 10320355456 10775597056 1386014976 14.470 98 12 24 2 1403722816 14587424 3120416
21:35:15 7048 8192000000 10308127584 10766221312 1388508416 14.493 99 37 73 0 1408074864 10470528 9095920
21:36:23 56589 8192000000 10290214352 10743533568 1367973696 14.309 98 25 49 0 1379190208 5309504 5907008
21:38:29 20124 8192000000 10298822048 10757193728 1394817936 14.549 100 26 52 1 1407032944 7224848 4990160
21:39:54 89227 8192000000 10287546496 10758066176 1358494720 14.224 97 11 22 0 1370752480 9703440 2554320
21:42:35 47124 8192000000 10288152416 10743042048 1360970272 14.246 98 17 34 0 1375723552 11136704 3616576
21:44:23 42271 8192000000 10267318512 10721546240 1320929552 13.885 96 19 38 1 1341923872 15962000 5032320
21:46:20 41485 8192000000 10283246688 10730905600 1356234400 14.204 99 50 100 2 1381761072 13373024 12153648
21:48:05 23456 8192000000 10277617584 10716852224 1315540368 13.836 96 45 90 0 1340491200 14490688 10460144
21:49:30 32321 8192000000 10264609168 10710650880 1317331088 13.853 97 20 40 0 1339769920 17833728 4605104
21:51:15 29735 8192000000 10255057248 10693341184 1320741328 13.883 98 15 30 1 1335621168 11734032 3145808
21:52:51 16224 8192000000 10263768576 10697043968 1324727376 13.919 98 41 82 1 1345335504 11627456 8980672
21:54:12 103970 8192000000 10236430160 10677653504 1301260016 13.707 97 13 26 0 1313316624 9164656 2891952
21:57:06 28717 8192000000 10251166608 10706747392 1338782064 14.046 100 23 46 1 1359342816 15425008 5135744
21:58:47 61213 8192000000 10254249072 10718633984 1340939120 14.066 99 14 28 0 1361861360 17912256 3009984
22:00:48 45816 8192000000 10254743504 10707243008 1343081328 14.085 99 11 22 0 1357284992 11530224 2673440
22:02:37 71195 8192000000 10264339024 10734669824 1369661488 14.324 100 9 18 0 1382644272 10787104 2195680
22:05:17 70246 8192000000 10278574944 10739101696 1372377536 14.348 100 16 32 0 1384828672 8034656 4416480
22:07:29 50735 8192000000 10304744880 10759192576 1387887680 14.487 100 26 52 0 1406758064 12817664 6052720
22:09:20 62534 8192000000 10306741264 10769686528 1396277360 14.562 100 12 25 0 1412199872 13158960 2763552
22:11:24 36664 8192000000 10325813504 10792062976 1407502976 14.662 100 32 64 2 1426458912 11723632 7232304
22:13:13 53183 8192000000 10330166032 10783858688 1389705360 14.503 98 34 68 0 1411609152 13817696 8086096
22:15:13 56834 8192000000 10323650288 10778791936 1405413664 14.643 100 27 54 2 1423092656 10823488 6855504
22:17:13 51591 8192000000 10313570448 10785042432 1386487472 14.475 98 18 36 0 1402995968 12581472 3927024
22:19:11 65107 8192000000 10299934784 10752225280 1330574512 13.972 95 12 24 1 1344123504 10662672 2886320
22:21:17 101498 8192000000 10293371088 10759368704 1378332416 14.402 99 37 74 1 1395787520 8313424 9141680
22:24:00 20276 8192000000 10295900592 10752409600 1388058000 14.489 100 25 50 1 1404817264 10447328 6311936
22:25:25 57395 8192000000 10305461120 10764345344 1394887808 14.549 100 18 35 1 1413400912 14097296 4415808
22:27:33 34959 8192000000 10270394608 10725306368 1343521616 14.089 98 22 44 0 1361862400 13677952 4662832
22:29:10 71063 8192000000 10283077088 10735460352 1347962064 14.129 98 19 38 0 1368684384 15180992 5541328
22:31:25 59076 8192000000 10294338352 10771816448 1373989424 14.363 99 19 39 0 1397688288 19482000 4216864
22:33:27 73391 8192000000 10279703632 10739884032 1329225408 13.960 96 19 38 1 1347029152 12964576 4839168
22:35:43 33649 8192000000 10289183440 10745528320 1364414384 14.277 99 35 70 1 1393448112 21395040 7638688
22:37:32 38970 8192000000 10259571632 10704338944 1294122480 13.642 95 37 74 2 1313999920 11942528 7934912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-06-2022 22:39:33 to 27-06-2022 22:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 95 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-06-2022 22:39:49 to 27-06-2022 22:39:51.

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          241143
MON$OLDEST_ACTIVE               244319
MON$OLDEST_SNAPSHOT             244319
MON$NEXT_TRANSACTION            244319
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                       2047520
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:536: 
:537: mscs-test-161.budzdorov.ru    Mon Jun 27 22:38:22 2022
:538:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:539: 
:540: 
:541: mscs-test-161.budzdorov.ru    Mon Jun 27 22:38:26 2022
:542:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:543: 
:544: 
:545: mscs-test-161.budzdorov.ru    Mon Jun 27 22:38:26 2022
:546:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:547: 
:548: 
:549: mscs-test-161.budzdorov.ru    Mon Jun 27 22:38:26 2022
:550:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:551: 
:552: 
:553: mscs-test-161.budzdorov.ru    Mon Jun 27 22:38:27 2022
:554:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:555: 
--- end of diff output ---

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

27.06.22 22:39:52. 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-06-27 22:39:54 No severe PSQL-related problems occured
27.06.22 22:39:56. End of report.