Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.12.2021 19: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 16814772 kB
MemAvailable 31480152 kB
Buffers 0 kB
Cached 14842036 kB
SwapCached 0 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.8
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 2021-12-04 19:37:56
DTS_END                         2021-12-04 19:37:56.4300
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/35942
TRN_ID                          232456
ATT_ID                          666
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: 9079.04 24749 1089485
customer order: creation 168.63 348 20235
customer order: refuse 14.07 333 1688
order to supplier: creation 44.03 1233 5283
order to supplier: removal 3.38 100967 406
invoice (draft): creation 45.73 3083 5488
invoice (draft): removal 51.14 775 6137
invoice accept: apply 41.00 18623 4920
invoice accept: cancel 46.44 751 5573
customer reserve: creation 3808.58 183 457030
customer reserve: removal 4566.43 58 547971
realization accept: apply 82.01 179 9841
realization accept: cancel 80.37 119 9644
payment from customer: creation 58.71 135 7045
payment from customer: removal 5.72 77 686
payment to supplier: creation 47.57 158 5708
payment to supplier: removal 3.40 89 408
service: total inventory turnovers 4.50 1499 540
service: total monetary turnovers 6.42 22 770
service: aggregate perf. data 0.90 16440 108
service: refresh index statistics 0.03 349907 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-12-2021 19:39:05 to 04-12-2021 19:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2211
TEST_TIME 2 3040
TEST_TIME 3 3955
TEST_TIME 4 3817
TEST_TIME 5 4147
TEST_TIME 6 4418
TEST_TIME 7 4878
TEST_TIME 8 5039
TEST_TIME 9 4994
TEST_TIME 10 5378
TEST_TIME 11 5784
TEST_TIME 12 5881
TEST_TIME 13 5882
TEST_TIME 14 6020
TEST_TIME 15 6681
TEST_TIME 16 7170
TEST_TIME 17 7355
TEST_TIME 18 7551
TEST_TIME 19 8036
TEST_TIME 20 8020
TEST_TIME 21 8236
TEST_TIME 22 8429
TEST_TIME 23 8982
TEST_TIME 24 9098
TEST_TIME 25 9708
TEST_TIME 26 9849
TEST_TIME 27 10300
TEST_TIME 28 11092
TEST_TIME 29 11090
TEST_TIME 30 10751
TEST_TIME 31 10542
TEST_TIME 32 10366
TEST_TIME 33 10412
TEST_TIME 34 10337
TEST_TIME 35 10162
TEST_TIME 36 10087
TEST_TIME 37 9976
TEST_TIME 38 9838
TEST_TIME 39 9746
TEST_TIME 40 9702
TEST_TIME 41 9663
TEST_TIME 42 9487
TEST_TIME 43 9409
TEST_TIME 44 9368
TEST_TIME 45 9221
TEST_TIME 46 9111
TEST_TIME 47 9076
TEST_TIME 48 9197
TEST_TIME 49 9105
TEST_TIME 50 9128
TEST_TIME 51 9163
TEST_TIME 52 9130
TEST_TIME 53 9156
TEST_TIME 54 9272
TEST_TIME 55 9367
TEST_TIME 56 9293
TEST_TIME 57 9328
TEST_TIME 58 9305
TEST_TIME 59 9448
TEST_TIME 60 9381
TEST_TIME 61 9330
TEST_TIME 62 9212
TEST_TIME 63 9239
TEST_TIME 64 9257
TEST_TIME 65 9181
TEST_TIME 66 9124
TEST_TIME 67 9112
TEST_TIME 68 9118
TEST_TIME 69 9144
TEST_TIME 70 9187
TEST_TIME 71 9194
TEST_TIME 72 9145
TEST_TIME 73 9294
TEST_TIME 74 9371
TEST_TIME 75 9337
TEST_TIME 76 9306
TEST_TIME 77 9269
TEST_TIME 78 9335
TEST_TIME 79 9396
TEST_TIME 80 9411
TEST_TIME 81 9409
TEST_TIME 82 9353
TEST_TIME 83 9285
TEST_TIME 84 9307
TEST_TIME 85 9265
TEST_TIME 86 9281
TEST_TIME 87 9230
TEST_TIME 88 9164
TEST_TIME 89 9165
TEST_TIME 90 9090
TEST_TIME 91 9109
TEST_TIME 92 9110
TEST_TIME 93 9100
TEST_TIME 94 9111
TEST_TIME 95 9078
TEST_TIME 96 9078
TEST_TIME 97 9157
TEST_TIME 98 9126
TEST_TIME 99 9097
TEST_TIME 100 9220
TEST_TIME 101 9217
TEST_TIME 102 9172
TEST_TIME 103 9281
TEST_TIME 104 9283
TEST_TIME 105 9296
TEST_TIME 106 9325
TEST_TIME 107 9329
TEST_TIME 108 9293
TEST_TIME 109 9225
TEST_TIME 110 9224
TEST_TIME 111 9177
TEST_TIME 112 9209
TEST_TIME 113 9164
TEST_TIME 114 9134
TEST_TIME 115 9104
TEST_TIME 116 9070
TEST_TIME 117 9069
TEST_TIME 118 9056
TEST_TIME 119 9057
TEST_TIME 120 9042
TEST_TIME 121 9007

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-12-2021 19:39:08 to 04-12-2021 19:39:12.

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 1512921 1512921 0 0.00 0 31279 6 0 0
doc_list_biud 1512921 1512921 0 0.00 0 44526 27 0 0
sp_add_invoice_to_stock 4992 4920 72 1.44 49 200314 18623 0 72
sp_cancel_adding_invoice 5573 5573 0 0.00 5 37731 752 0 0
sp_cancel_client_order 1688 1688 0 0.00 4 10523 333 0 0
sp_cancel_customer_reserve 547971 547971 0 0.00 1 44528 59 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 4470 77 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 4828 90 0 0
sp_cancel_supplier_invoice 6144 6137 7 0.11 2 12855 775 0 7
sp_cancel_supplier_order 406 406 0 0.00 7 1262979 100967 0 0
sp_cancel_write_off 9644 9644 0 0.00 1 8794 119 0 0
sp_client_order 20235 20235 0 0.00 3 23771 349 0 0
sp_customer_reserve 457030 457030 0 0.00 0 60810 183 0 0
sp_fill_shopping_cart 30994 30994 0 0.00 1 16202 37 0 0
sp_fill_shopping_cart_clo_res 457022 457022 0 0.00 0 17921 14 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 30859 332 0 0
sp_kill_cost_storno 16311 16311 0 0.00 0 8092 57 0 0
sp_kill_qstorno_ret_qs2qd 1112129 1112129 0 0.00 0 44520 32 0 0
sp_kill_qty_storno 586172 586172 0 0.00 0 44526 69 0 0
sp_lock_selected_doc 20354 20354 0 0.00 0 6623 5 0 0
sp_make_cost_storno 27506 27506 0 0.00 0 31266 59 0 0
sp_multiply_rows_for_pdistr 27506 27506 0 0.00 0 9287 35 0 0
sp_multiply_rows_for_qdistr 20235 20235 0 0.00 0 17661 156 0 0
sp_pay_from_customer 7045 7045 0 0.00 4 26805 135 0 0
sp_pay_to_supplier 5708 5708 0 0.00 5 20153 158 0 0
sp_payment_common 12753 12753 0 0.00 1 26316 37 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 10514 311 0 0
sp_qd_handle_on_invoice_upd_sts 10485 10485 0 0.00 0 34798 348 0 0
sp_qd_handle_on_reserve_upd_sts 9841 9841 0 0.00 0 13173 57 0 0
sp_reserve_write_off 9841 9841 0 0.00 7 13195 180 0 0
sp_supplier_invoice 5488 5488 0 0.00 6 76096 3084 0 0
sp_supplier_order 5283 5283 0 0.00 5 36922 1234 0 0
srv_aggregate_perf_data 108 108 0 0.00 2734 173956 16441 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 3235 136078 48840 0 0
srv_find_qd_qs_mism 1042630 1042630 0 0.00 0 21073 5 0 0
srv_make_invnt_saldo 540 540 0 0.00 36 204231 1499 0 0
srv_make_money_saldo 770 770 0 0.00 0 780 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 224716 479722 349907 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 0 15551 176 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 0 8453 140 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 9589 21 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 757 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 13666 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 471 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 4380 20 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 19459 1495 0 0
t$perf-norm:sp_cancel_write_off 922 922 0 0.00 0 902 3 0 0
t$perf-norm:sp_client_order 20236 20236 0 0.00 0 11800 18 0 0
t$perf-norm:sp_customer_reserve 943 943 0 0.00 0 1332 7 0 0
t$perf-norm:sp_pay_from_customer 7045 7045 0 0.00 0 11615 8 0 0
t$perf-norm:sp_pay_to_supplier 5708 5708 0 0.00 0 6440 13 0 0
t$perf-norm:sp_reserve_write_off 9841 9841 0 0.00 0 7420 11 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 12690 23 0 0
t$perf-norm:sp_supplier_order 5283 5283 0 0.00 0 12068 19 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 357 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3484 58 0 0
t$perf-norm:srv_make_invnt_saldo 540 540 0 0.00 0 4548 40 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 5179 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 9964 9820 144 1.44 0 4877 17 0 144
v_all_customers 37046 37046 0 0.00 0 54 1 0 0
v_all_suppliers 29826 29826 0 0.00 0 55 1 0 0
v_all_wares 40464 40464 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 1009 10 0 0
v_cancel_client_order 3376 3376 0 0.00 0 2686 4 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 1675 4 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 3430 10 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 2706 10 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 4837 20 0 14
v_cancel_supplier_order 812 812 0 0.00 0 229 3 0 0
v_cancel_write_off 1844 1844 0 0.00 0 3940 13 0 0
v_max_id_clo_ord 5281 5281 0 0.00 0 346 1 0 0
v_max_id_clo_res 943 943 0 0.00 0 2150 10 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 5683 6 0 0
v_max_non_paid_invoice 1556 1556 0 0.00 0 3747 5 0 0
v_max_non_paid_realizn 3675 3675 0 0.00 0 3006 5 0 0
v_min_id_clo_ord 5281 5281 0 0.00 0 1633 5 0 0
v_min_id_clo_res 943 943 0 0.00 0 6450 9 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 5531 8 0 0
v_min_non_paid_invoice 5708 5708 0 0.00 0 20131 28 0 0
v_min_non_paid_realizn 7045 7045 0 0.00 0 26313 28 0 0
v_our_firm 10158 10158 0 0.00 0 89 1 0 0
v_reserve_write_off 19682 19682 0 0.00 0 4832 6 0 0
x_lock_depdocs_on_canc_invoice 11299 11299 0 0.00 0 19851 124 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 9123 350 0 0
x_make_qstorno_1000_1200 5283 5283 0 0.00 1 36913 1158 0 0
x_make_qstorno_1200_2000 5488 5488 0 0.00 3 76089 2952 0 0
x_make_qstorno_2100_3300 457030 457030 0 0.00 0 60805 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-12-2021 19:39:13 to 04-12-2021 19:39: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
17:08:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:01 33719 8192000000 9911170256 10224701440 993219936 10.813 100 88 177 1 1022116592 5937024 22959632
17:10:39 19422 8192000000 9948306064 10273378304 1030181712 11.170 100 87 174 1 1060489136 7998992 22308432
17:12:04 14196 8192000000 9990951648 10324897792 1070872816 11.560 100 85 170 0 1101564048 9573408 21117824
17:13:24 15091 8192000000 10035143088 10386354176 1124122688 12.066 100 75 150 0 1153289136 10902848 18263600
17:14:41 6910 8192000000 10084489952 10444771328 1171477648 12.511 100 81 162 0 1203253904 13113824 18662432
17:15:51 5872 8192000000 10110308064 10484174848 1212351568 12.891 100 79 158 1 1242863968 12058128 18454272
17:16:58 2854 8192000000 10165858320 10544590848 1251356768 13.251 100 83 164 1 1281853232 12869152 17627312
17:18:04 1966 8192000000 10200802240 10581159936 1281138256 13.523 100 85 170 0 1314748032 15142304 18467472
17:19:07 2135 8192000000 10213862128 10599911424 1295413168 13.654 100 88 177 1 1328175312 13056144 19706000
17:20:14 6055 8192000000 10216517600 10607603712 1302392656 13.717 100 83 170 5 1334227008 12165984 19668368
17:21:22 8551 8192000000 10206479904 10613420032 1306891904 13.758 100 37 74 1 1323814912 8765312 8157696
17:22:31 9039 8192000000 10198502080 10609963008 1311647856 13.801 100 26 53 2 1323490224 5510736 6331632
17:23:40 10126 8192000000 10199655904 10618187776 1317270064 13.852 100 16 32 0 1326165856 5729872 3165920
17:24:52 24528 8192000000 10232367408 10637336576 1320194064 13.878 100 70 144 4 1343850768 5799600 17857104
17:26:16 3347 8192000000 10223178432 10637754368 1322619840 13.900 100 38 75 1 1337497648 6078640 8799168
17:27:21 5327 8192000000 10212278848 10630803456 1309653696 13.783 99 23 45 1 1319756448 5256656 4846096
17:28:27 10884 8192000000 10210876560 10628030464 1305295728 13.743 99 54 108 1 1323680480 5516288 12868464
17:29:39 11741 8192000000 10161015680 10568896512 1210778544 12.876 93 15 29 1 1219370832 5232336 3359952
17:30:53 7067 8192000000 10160594064 10567299072 1252567248 13.262 98 21 41 1 1263561936 6196032 4798656
17:32:03 63216 8192000000 10137807584 10539540480 1207683872 12.848 96 33 64 0 1220499728 5273312 7542544
17:34:07 5304 8192000000 10166131312 10575003648 1276347040 13.480 100 33 68 2 1289908464 5345232 8216192
17:35:13 9457 8192000000 10179436944 10596290560 1295141168 13.651 100 13 26 1 1303623680 5502800 2979712
17:36:23 9058 8192000000 10191899120 10609319936 1304569152 13.737 100 16 32 0 1314897568 6200880 4127536
17:37:35 55622 8192000000 10218783136 10632118272 1309468288 13.781 100 17 34 0 1318605584 5488960 3648336
17:39:40 28737 8192000000 10257160976 10671910912 1347601744 14.126 100 47 94 1 1368123488 9560272 10961472
17:41:17 31748 8192000000 10287318992 10701221888 1371458288 14.340 100 58 116 0 1401886704 17327488 13100928
17:42:53 15268 8192000000 10282998864 10711023616 1381130960 14.427 100 45 90 0 1408937056 17072784 10733312
17:44:12 4581 8192000000 10310561696 10731438080 1391771744 14.522 100 54 108 1 1421939872 17524304 12643824
17:45:22 23655 8192000000 10305659088 10739855360 1397735568 14.575 100 33 66 4 1425543760 19686944 8121248
17:47:04 4749 8192000000 10339244896 10762944512 1411683376 14.699 100 72 143 1 1452627968 23459184 17485408
17:48:14 12587 8192000000 10342285760 10767872000 1414625216 14.725 100 57 114 1 1453996112 25372656 13998240
17:49:44 16598 8192000000 10370902880 10800152576 1430154288 14.863 100 65 128 1 1483639552 37679280 15805984
17:51:03 3235 8192000000 10398194112 10817646592 1441335456 14.961 100 85 168 1 1502527280 40135792 21056032
17:52:10 4972 8192000000 10387703856 10807209984 1435272832 14.908 100 79 159 1 1494887296 39990496 19623968
17:53:23 3421 8192000000 10390670416 10806149120 1418336704 14.758 99 75 149 1 1483195248 46266928 18591616
17:54:32 3492 8192000000 10389290784 10807918592 1420707504 14.779 100 77 154 1 1491616368 51429216 19479648
17:55:39 16290 8192000000 10366751344 10798600192 1405048320 14.640 99 40 80 0 1467206144 51213136 10944688
17:56:59 17940 8192000000 10340418512 10770546688 1392252096 14.526 100 30 60 1 1446786752 46199904 8334752
17:58:23 8652 8192000000 10332015632 10754531328 1364657184 14.279 99 41 82 0 1435490720 59503360 11330176
17:59:39 7040 8192000000 10331568768 10749145088 1359796768 14.236 99 46 92 0 1437327248 64824304 12706176
18:00:47 14961 8192000000 10321608896 10743328768 1371166112 14.337 100 40 79 2 1432215312 51226000 9823200
18:02:35 28792 8192000000 10296264464 10733121536 1342435360 14.079 99 23 47 1 1403968400 54795456 6737584
18:04:05 35865 8192000000 10287366000 10721075200 1314612080 13.828 98 15 30 0 1367628640 48682032 4334528
18:05:42 36458 8192000000 10272194544 10701828096 1353605840 14.180 100 31 60 1 1372597104 10238256 8753008
18:07:19 125004 8192000000 10293810784 10729881600 1363398432 14.268 99 55 110 3 1387887984 12337200 12152352
18:10:28 67812 8192000000 10273118496 10732982272 1341313440 14.069 98 15 31 0 1357366768 12810080 3243248
18:12:37 57581 8192000000 10270536368 10716372992 1342212960 14.077 97 13 26 1 1356358576 11445120 2700496
18:14:39 86471 8192000000 10261382432 10708709376 1333349040 13.997 97 13 27 1 1351313856 14441552 3523264
18:17:10 101619 8192000000 10269123008 10723942400 1355709104 14.199 98 32 64 0 1372126464 8591216 7826144
18:19:53 13690 8192000000 10272113680 10725347328 1372760416 14.352 100 23 47 1 1386588064 8464256 5363392
18:21:12 63644 8192000000 10268817152 10736263168 1379948864 14.416 100 9 18 0 1391721808 9839664 1933280
18:23:16 93747 8192000000 10284158896 10752180224 1370092640 14.328 99 13 26 0 1383310400 10402432 2815328
18:26:18 17727 8192000000 10282728368 10735644672 1383449728 14.447 100 33 67 1 1398365152 6428144 8487280
18:27:37 15075 8192000000 10298581440 10747797504 1394681968 14.548 100 36 73 0 1412039536 9401920 7955648
18:28:53 39569 8192000000 10300748096 10755731456 1390951744 14.514 100 28 56 0 1410251216 13358912 5940560
18:30:33 60958 8192000000 10301460512 10765201408 1361805376 14.254 97 16 32 0 1381144288 15596224 3742688
18:32:57 70971 8192000000 10308155168 10754940928 1379555760 14.413 99 18 36 0 1395559024 12208576 3794688
18:35:45 50254 8192000000 10305044288 10755080192 1384393888 14.456 99 20 40 1 1400247984 11036480 4817616
18:37:39 58615 8192000000 10292878688 10739068928 1322458512 13.899 95 29 58 0 1342941216 14245712 6236992
18:39:45 93670 8192000000 10287289280 10746417152 1374065488 14.363 99 24 48 0 1394733360 15491024 5176848
18:42:35 44838 8192000000 10279809952 10743123968 1383286128 14.446 100 7 14 0 1393069008 8168448 1614432
18:44:20 39624 8192000000 10265787104 10723250176 1315629600 13.837 95 14 28 1 1324426400 6180384 2616416
18:46:01 85618 8192000000 10281301952 10731810816 1316500576 13.845 95 27 54 0 1336974944 13824528 6649840
18:49:12 38394 8192000000 10276473232 10713571328 1342438432 14.079 98 45 89 0 1363663296 10077312 11147552
18:52:00 99572 8192000000 10250266080 10688638976 1222179808 12.982 89 17 34 0 1238818528 12939888 3698832
18:54:43 33872 8192000000 10279886928 10708754432 1320196976 13.878 97 44 88 0 1342153328 12392080 9564272
18:56:20 77877 8192000000 10275063888 10712932352 1278133088 13.496 94 20 40 0 1297900496 15575520 4191888
18:58:43 126130 8192000000 10256573888 10697146368 1341126496 14.068 99 26 52 0 1356852176 10186912 5538768
19:01:51 17119 8192000000 10247656176 10699509760 1341746688 14.073 100 21 42 1 1358771504 12125488 4899328
19:03:08 136078 8192000000 10249126912 10705014784 1301254544 13.707 96 7 14 0 1314951872 12427536 1269792
19:06:25 75295 8192000000 10263124848 10719260672 1359503840 14.233 100 12 24 0 1374856224 12852256 2500128
19:08:40 21746 8192000000 10256071696 10704408576 1343220896 14.086 99 23 45 0 1359549952 10579776 5749280
19:10:10 115899 8192000000 10274941840 10730106880 1357364912 14.214 99 12 24 0 1370880848 10796528 2719408
19:13:10 24479 8192000000 10313372784 10751934464 1391336096 14.518 100 49 98 0 1415947680 13466288 11145296
19:14:37 63953 8192000000 10292101344 10740748288 1337383552 14.034 96 12 24 0 1352706704 12703280 2619872
19:16:46 82909 8192000000 10293125584 10761457664 1373518496 14.359 99 9 18 1 1390733264 14597344 2617424
19:19:12 23822 8192000000 10326864432 10763915264 1401122128 14.605 100 39 78 1 1420887376 11527472 8237776
19:20:40 63805 8192000000 10291566208 10749304832 1357883616 14.218 97 15 30 1 1380047504 19135552 3028336
19:22:46 61765 8192000000 10299113040 10760720384 1382741760 14.441 99 12 24 0 1399242464 13765136 2735568
19:24:49 80270 8192000000 10289142592 10741133312 1384056416 14.453 99 27 54 0 1403597296 12960320 6580560
19:27:41 51895 8192000000 10299192304 10746130432 1380583008 14.422 99 31 61 2 1398427424 9960048 7884368
19:29:33 128164 8192000000 10266441552 10727387136 1293842320 13.639 93 10 20 0 1305437600 9421056 2174224
19:32:41 49149 8192000000 10273411312 10728030208 1365639792 14.288 99 14 27 1 1375210272 6835040 2735440
19:35:23 36479 8192000000 10287510816 10736607232 1371049504 14.336 99 19 38 0 1382340304 7270304 4020496
19:37:04 58234 8192000000 9447849232 9762545664 579309648 6.604 42 11 22 0 594942336 13088064 2544624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 04-12-2021 19:39:19 to 04-12-2021 19:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-12-2021 19:39:34 to 04-12-2021 19:39:36.

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          232940
MON$OLDEST_ACTIVE               232941
MON$OLDEST_SNAPSHOT             232941
MON$NEXT_TRANSACTION            232941
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                       2018368
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33541 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33541 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33541 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33541 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Sat Dec  4 19:38:09 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
--- end of diff output ---

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

04.12.21 19:39:37. 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
2021-12-04 19:39:39 No severe PSQL-related problems occured
04.12.21 19:39:41. End of report.