Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.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 16251320 kB
MemAvailable 31258660 kB
Buffers 0 kB
Cached 15404132 kB
SwapCached 2324 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.9
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-01-12 22:38:02
DTS_END                         2022-01-12 22:38:02.4860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51820
TRN_ID                          246693
ATT_ID                          723
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: 9708.28 23223 1164993
customer order: creation 177.00 333 21240
customer order: refuse 14.94 350 1793
order to supplier: creation 47.24 1148 5669
order to supplier: removal 3.58 106698 430
invoice (draft): creation 45.70 2956 5484
invoice (draft): removal 56.04 743 6725
invoice accept: apply 42.62 17337 5114
invoice accept: cancel 51.43 714 6171
customer reserve: creation 3926.45 173 471174
customer reserve: removal 5030.83 59 603699
realization accept: apply 84.39 192 10127
realization accept: cancel 96.04 110 11525
payment from customer: creation 60.38 136 7245
payment from customer: removal 5.69 73 683
payment to supplier: creation 50.39 134 6047
payment to supplier: removal 3.53 88 424
service: total inventory turnovers 4.57 1454 548
service: total monetary turnovers 6.63 25 796
service: aggregate perf. data 0.79 19689 95
service: refresh index statistics 0.03 312046 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-01-2022 22:38:45 to 12-01-2022 22:38:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5424
TEST_TIME 2 4030
TEST_TIME 3 3259
TEST_TIME 4 3425
TEST_TIME 5 3856
TEST_TIME 6 4533
TEST_TIME 7 4568
TEST_TIME 8 4920
TEST_TIME 9 5098
TEST_TIME 10 5221
TEST_TIME 11 6057
TEST_TIME 12 6123
TEST_TIME 13 6209
TEST_TIME 14 6297
TEST_TIME 15 6313
TEST_TIME 16 6571
TEST_TIME 17 7029
TEST_TIME 18 7327
TEST_TIME 19 7950
TEST_TIME 20 8278
TEST_TIME 21 8242
TEST_TIME 22 8584
TEST_TIME 23 8843
TEST_TIME 24 9315
TEST_TIME 25 9877
TEST_TIME 26 10137
TEST_TIME 27 11176
TEST_TIME 28 11010
TEST_TIME 29 10991
TEST_TIME 30 10787
TEST_TIME 31 10870
TEST_TIME 32 10811
TEST_TIME 33 10815
TEST_TIME 34 10737
TEST_TIME 35 10815
TEST_TIME 36 10743
TEST_TIME 37 10657
TEST_TIME 38 10503
TEST_TIME 39 10304
TEST_TIME 40 10208
TEST_TIME 41 10388
TEST_TIME 42 10323
TEST_TIME 43 10172
TEST_TIME 44 10203
TEST_TIME 45 10272
TEST_TIME 46 10201
TEST_TIME 47 10434
TEST_TIME 48 10360
TEST_TIME 49 10310
TEST_TIME 50 10250
TEST_TIME 51 10183
TEST_TIME 52 10082
TEST_TIME 53 10048
TEST_TIME 54 9988
TEST_TIME 55 9848
TEST_TIME 56 9792
TEST_TIME 57 9839
TEST_TIME 58 9801
TEST_TIME 59 9766
TEST_TIME 60 9713
TEST_TIME 61 9808
TEST_TIME 62 9868
TEST_TIME 63 9944
TEST_TIME 64 10061
TEST_TIME 65 10098
TEST_TIME 66 10068
TEST_TIME 67 10103
TEST_TIME 68 10175
TEST_TIME 69 10191
TEST_TIME 70 10254
TEST_TIME 71 10275
TEST_TIME 72 10181
TEST_TIME 73 10104
TEST_TIME 74 10051
TEST_TIME 75 10034
TEST_TIME 76 10062
TEST_TIME 77 9994
TEST_TIME 78 9925
TEST_TIME 79 9892
TEST_TIME 80 9794
TEST_TIME 81 9788
TEST_TIME 82 9819
TEST_TIME 83 9873
TEST_TIME 84 9844
TEST_TIME 85 9807
TEST_TIME 86 9893
TEST_TIME 87 9984
TEST_TIME 88 9968
TEST_TIME 89 9927
TEST_TIME 90 9917
TEST_TIME 91 9997
TEST_TIME 92 9991
TEST_TIME 93 10051
TEST_TIME 94 10066
TEST_TIME 95 10012
TEST_TIME 96 9939
TEST_TIME 97 9921
TEST_TIME 98 9904
TEST_TIME 99 9874
TEST_TIME 100 9845
TEST_TIME 101 9821
TEST_TIME 102 9830
TEST_TIME 103 9778
TEST_TIME 104 9744
TEST_TIME 105 9707
TEST_TIME 106 9700
TEST_TIME 107 9660
TEST_TIME 108 9677
TEST_TIME 109 9699
TEST_TIME 110 9690
TEST_TIME 111 9691
TEST_TIME 112 9665
TEST_TIME 113 9723
TEST_TIME 114 9725
TEST_TIME 115 9735
TEST_TIME 116 9739
TEST_TIME 117 9729
TEST_TIME 118 9751
TEST_TIME 119 9784
TEST_TIME 120 9709

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-01-2022 22:38:49 to 12-01-2022 22:38:52.

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 1602792 1602792 0 0.00 0 21965 6 0 0
doc_list_biud 1602792 1602792 0 0.00 0 29569 28 0 0
sp_add_invoice_to_stock 5274 5114 160 3.03 26 176421 17338 0 160
sp_cancel_adding_invoice 6171 6171 0 0.00 3 54442 714 0 0
sp_cancel_client_order 1793 1793 0 0.00 3 16213 351 0 0
sp_cancel_customer_reserve 603699 603699 0 0.00 1 29571 60 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 4258 74 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 7202 88 0 0
sp_cancel_supplier_invoice 6737 6725 12 0.17 1 16032 744 0 12
sp_cancel_supplier_order 430 430 0 0.00 6 1122085 106699 0 0
sp_cancel_write_off 11525 11525 0 0.00 1 21398 110 0 0
sp_client_order 21240 21240 0 0.00 3 22564 333 0 0
sp_customer_reserve 471174 471174 0 0.00 0 33547 173 0 0
sp_fill_shopping_cart 32388 32388 0 0.00 1 12146 35 0 0
sp_fill_shopping_cart_clo_res 471168 471168 0 0.00 0 28070 14 0 0
sp_get_clo_for_invoice 5108 5108 0 0.00 0 25447 283 0 0
sp_kill_cost_storno 18803 18803 0 0.00 0 21395 56 0 0
sp_kill_qstorno_ret_qs2qd 1226078 1226078 0 0.00 0 29567 32 0 0
sp_kill_qty_storno 645578 645578 0 0.00 0 29569 69 0 0
sp_lock_selected_doc 21214 21214 0 0.00 0 7537 5 0 0
sp_make_cost_storno 28527 28527 0 0.00 0 21958 62 0 0
sp_multiply_rows_for_pdistr 28527 28527 0 0.00 0 18660 30 0 0
sp_multiply_rows_for_qdistr 21240 21240 0 0.00 0 21975 142 0 0
sp_pay_from_customer 7245 7245 0 0.00 4 18668 137 0 0
sp_pay_to_supplier 6047 6047 0 0.00 5 10524 135 0 0
sp_payment_common 13292 13292 0 0.00 1 18495 38 0 0
sp_qd_handle_on_cancel_clo 1793 1793 0 0.00 0 16205 323 0 0
sp_qd_handle_on_invoice_upd_sts 11279 11279 0 0.00 0 19418 326 0 0
sp_qd_handle_on_reserve_upd_sts 10127 10127 0 0.00 0 14779 56 0 0
sp_reserve_write_off 10127 10127 0 0.00 6 14866 193 0 0
sp_supplier_invoice 5484 5484 0 0.00 6 72186 2957 0 0
sp_supplier_order 5669 5669 0 0.00 5 65717 1148 0 0
srv_aggregate_perf_data 96 95 1 1.04 2750 170479 19690 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 840 148433 52295 0 0
srv_find_qd_qs_mism 1116989 1116989 0 0.00 0 10440 5 0 0
srv_make_invnt_saldo 548 548 0 0.00 26 151412 1455 0 0
srv_make_money_saldo 796 796 0 0.00 1 1195 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 258558 421223 312047 0 0
t$perf-abend:sp_add_invoice_to_stock 160 160 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5114 5114 0 0.00 0 16133 158 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4926 71 0 0
t$perf-norm:sp_cancel_client_order 1793 1793 0 0.00 0 14809 29 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 2915 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 1352 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 593 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 1555 13 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 26352 1947 0 0
t$perf-norm:sp_cancel_write_off 1093 1093 0 0.00 0 2655 8 0 0
t$perf-norm:sp_client_order 21240 21240 0 0.00 0 18332 19 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 533 4 0 0
t$perf-norm:sp_pay_from_customer 7245 7245 0 0.00 0 13528 16 0 0
t$perf-norm:sp_pay_to_supplier 6047 6047 0 0.00 0 3981 10 0 0
t$perf-norm:sp_reserve_write_off 10127 10127 0 0.00 0 8338 15 0 0
t$perf-norm:sp_supplier_invoice 5484 5484 0 0.00 0 6373 15 0 0
t$perf-norm:sp_supplier_order 5669 5669 0 0.00 0 10337 19 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 587 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 28 2 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 540 14 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 4133 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10536 10216 320 3.03 0 9755 19 0 320
v_all_customers 38900 38900 0 0.00 0 19 1 0 0
v_all_suppliers 31254 31254 0 0.00 0 24 1 0 0
v_all_wares 42480 42480 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1603 7 0 0
v_cancel_client_order 3586 3586 0 0.00 0 3313 8 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 4093 9 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 276 3 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 1917 11 0 0
v_cancel_supplier_invoice 918 894 24 2.61 0 356 13 0 24
v_cancel_supplier_order 860 860 0 0.00 0 1295 6 0 0
v_cancel_write_off 2186 2186 0 0.00 0 5289 16 0 0
v_max_id_clo_ord 5668 5668 0 0.00 0 181 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 5254 35 0 0
v_max_id_ord_sup 5480 5480 0 0.00 0 4503 4 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 870 4 0 0
v_max_non_paid_realizn 3604 3604 0 0.00 0 1315 4 0 0
v_min_id_clo_ord 5668 5668 0 0.00 0 12108 10 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 723 3 0 0
v_min_id_ord_sup 5480 5480 0 0.00 0 4328 4 0 0
v_min_non_paid_invoice 6047 6047 0 0.00 0 8555 27 0 0
v_min_non_paid_realizn 7245 7245 0 0.00 0 10696 32 0 0
v_our_firm 10862 10862 0 0.00 0 13 1 0 0
v_reserve_write_off 20254 20254 0 0.00 0 10793 8 0 0
x_lock_depdocs_on_canc_invoice 12449 12449 0 0.00 0 8931 108 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 16246 376 0 0
x_make_qstorno_1000_1200 5669 5669 0 0.00 1 65598 1063 0 0
x_make_qstorno_1200_2000 5484 5484 0 0.00 2 72153 2841 0 0
x_make_qstorno_2100_3300 471174 471174 0 0.00 0 32952 157 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 12-01-2022 22:38:53 to 12-01-2022 22:38:58.

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:05 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:07 28601 8192000000 9921515872 10239295488 1003489264 10.912 100 87 174 0 1032196592 6049264 22658064
20:10:40 11843 8192000000 9954277104 10281091072 1039253248 11.257 100 88 176 1 1069990848 8353376 22384224
20:12:01 22920 8192000000 9998189280 10334334976 1077049600 11.619 100 90 180 0 1108460688 9251984 22159104
20:13:33 10424 8192000000 10047590480 10395471872 1126035440 12.084 100 75 148 2 1153945792 10917312 16993040
20:14:53 9259 8192000000 10091465856 10451238912 1167300272 12.472 100 74 148 0 1196654160 12767648 16586240
20:16:04 5344 8192000000 10124579120 10494754816 1214212320 12.908 100 77 154 3 1244517488 13009536 17295632
20:17:12 3443 8192000000 10160670304 10535845888 1247414272 13.214 100 80 160 1 1279008080 13352096 18241712
20:18:17 1934 8192000000 10204670784 10584449024 1283269808 13.543 100 94 186 1 1317783760 13593664 20920288
20:19:20 8595 8192000000 10205037840 10593546240 1297469248 13.672 100 79 156 1 1326667136 11332768 17865120
20:20:30 8654 8192000000 10200318288 10600800256 1300749888 13.702 100 48 96 0 1320538816 9475376 10313552
20:21:40 4367 8192000000 10202202576 10608893952 1310900400 13.794 100 41 80 1 1327671200 7337328 9433472
20:22:45 7155 8192000000 10205742112 10619482112 1314988832 13.831 100 20 40 1 1329892368 10149232 4754304
20:23:56 11955 8192000000 10213237696 10629996544 1324961984 13.922 100 42 84 0 1341510112 5545104 11003024
20:25:09 6807 8192000000 10221847456 10639601664 1327681376 13.946 100 27 54 0 1340226192 6627360 5917456
20:26:20 3853 8192000000 10242541776 10654162944 1333273632 13.997 100 32 64 0 1347116464 7021632 6821200
20:27:25 7861 8192000000 10208592912 10628116480 1301754032 13.711 98 26 53 0 1313954992 6661584 5539376
20:28:36 5493 8192000000 10189302720 10598682624 1276423120 13.480 98 29 58 2 1289289152 6391840 6474192
20:29:43 25405 8192000000 10168501056 10558005248 1186379840 12.650 92 42 85 2 1202057488 4529968 11147680
20:31:08 7211 8192000000 10138647920 10534699008 1212692656 12.894 96 22 44 0 1223720256 5496080 5531520
20:32:17 6160 8192000000 10145537600 10542559232 1243390400 13.177 99 30 60 2 1256699440 5870896 7438144
20:33:24 6159 8192000000 10159162736 10563928064 1267412240 13.398 100 28 57 1 1279653888 5805376 6436272
20:34:33 22530 8192000000 10200834960 10591715328 1283399712 13.544 100 78 157 0 1309698544 5980224 20318608
20:35:56 3660 8192000000 10197428176 10603216896 1297835296 13.676 100 40 83 0 1314506256 7726352 8944608
20:37:00 6159 8192000000 10197281008 10610491392 1304513776 13.736 100 19 38 0 1313817872 4974624 4329472
20:38:12 89838 8192000000 10236067472 10649460736 1321809264 13.893 100 33 66 2 1334312256 5611008 6891984
20:40:45 87416 8192000000 10271269936 10687504384 1359742416 14.235 100 47 94 0 1384067968 14148608 10176944
20:43:18 67942 8192000000 10281261056 10707881984 1368352352 14.312 100 25 50 3 1392466784 17892976 6221456
20:45:37 11456 8192000000 10300125344 10723819520 1382453728 14.438 100 31 60 0 1412617680 22822240 7341712
20:46:53 7466 8192000000 10332656544 10753089536 1398834352 14.585 100 59 117 0 1442454528 29346032 14274144
20:48:16 7578 8192000000 10349418192 10771689472 1412521984 14.706 100 63 125 0 1462374704 33658688 16194032
20:49:31 7576 8192000000 10352990096 10781200384 1418995456 14.764 100 51 100 2 1465830976 34459744 12375776
20:50:45 7519 8192000000 10355478368 10778681344 1409344496 14.678 99 61 120 1 1458295408 33421936 15528976
20:51:57 2853 8192000000 10352050256 10759217152 1393548784 14.538 99 85 169 1 1453581088 38254336 21777968
20:53:06 4569 8192000000 10365739120 10775052288 1405317216 14.642 100 70 136 0 1468052064 45340656 17394192
20:54:20 840 8192000000 10373761296 10777559040 1404226800 14.633 100 89 178 0 1475875312 49164256 22484256
20:55:25 2047 8192000000 10373800544 10776014848 1396019168 14.560 100 97 195 3 1474696560 54747872 23929520
20:56:32 10837 8192000000 10363214608 10785140736 1402846192 14.620 100 47 94 2 1471847648 56561056 12440400
20:57:52 5256 8192000000 10335103648 10757451776 1375379888 14.375 100 42 84 3 1440393936 53325280 11688768
20:59:04 15586 8192000000 10334098688 10759315456 1373599840 14.359 99 41 82 2 1436349856 51226288 11523728
21:00:26 12488 8192000000 10337269136 10763329536 1370447520 14.331 99 47 94 1 1440414304 56991392 12975392
21:01:41 21995 8192000000 10332718752 10769735680 1369350784 14.321 100 28 56 0 1442276400 64566272 8359344
21:03:06 23255 8192000000 10307949600 10739167232 1340713616 14.064 99 33 67 0 1407148912 56991024 9444272
21:04:31 29158 8192000000 10281226640 10723184640 1338086336 14.040 100 20 40 0 1383128400 39460208 5581856
21:06:01 30751 8192000000 10248039648 10690723840 1315722768 13.838 98 7 14 0 1326899040 9580128 1596144
21:07:37 70128 8192000000 10274791600 10711044096 1355357424 14.196 100 26 52 0 1371518640 10144672 6016544
21:09:48 148433 8192000000 10275524960 10725597184 1314786752 13.829 96 8 16 1 1326831536 10428560 1616224
21:13:19 110911 8192000000 10277649552 10719072256 1299558688 13.691 94 10 20 0 1313414768 11952704 1903376
21:16:11 108668 8192000000 10313331104 10764091392 1392802448 14.531 100 25 50 1 1413451056 14797744 5850864
21:19:05 56739 8192000000 10315260848 10766004224 1401394368 14.607 100 59 118 1 1439462304 24143616 13924320
21:21:04 22076 8192000000 10301647856 10763935744 1394315760 14.544 100 13 26 0 1411163216 13822608 3024848
21:22:29 26730 8192000000 10323261024 10771169280 1392795456 14.531 99 33 64 2 1421135888 20035344 8305088
21:24:04 71240 8192000000 10322050144 10787061760 1397460752 14.572 100 41 82 0 1427780512 20105792 10213968
21:26:16 10783 8192000000 10318074304 10771456000 1396744688 14.566 100 24 48 0 1412358272 9984432 5629152
21:27:28 44292 8192000000 10279540848 10743468032 1347151840 14.122 96 6 12 0 1357923952 9046592 1725520
21:29:20 63343 8192000000 10271557584 10731114496 1365244112 14.284 99 10 20 0 1374275744 6924496 2107136
21:31:31 29295 8192000000 10287868960 10747760640 1377460880 14.394 99 13 26 1 1388603072 8019296 3122896
21:33:11 89579 8192000000 10269055056 10735964160 1348902064 14.138 97 21 42 0 1369348320 14955920 5490336
21:35:50 56542 8192000000 10271076640 10738950144 1327973392 13.949 96 5 10 0 1338873152 9862112 1037648
21:37:46 45787 8192000000 10303474352 10762547200 1359269216 14.231 97 23 46 0 1376681744 12053136 5359392
21:39:54 86161 8192000000 10264476944 10705809408 1283443888 13.544 93 14 28 2 1308905056 22067088 3394080
21:42:21 43909 8192000000 10268753072 10711998464 1322088352 13.896 97 18 36 0 1333397312 7797232 3511728
21:44:06 25592 8192000000 10286960016 10729148416 1355055248 14.193 99 38 76 2 1373400592 9920592 8424752
21:45:33 129563 8192000000 10264867168 10716430336 1227120528 13.027 89 13 26 0 1244165584 14266752 2778304
21:48:43 107029 8192000000 10253218336 10703036416 1299049568 13.687 96 11 20 1 1316152752 14852976 2250208
21:52:19 91067 8192000000 10270692464 10732486656 1347301312 14.123 99 29 58 1 1367731264 14109760 6320192
21:54:50 67111 8192000000 10262916336 10719121408 1353198816 14.176 99 10 20 0 1364493360 9119248 2175296
21:57:06 70884 8192000000 10265174624 10726064128 1339840352 14.056 98 21 42 2 1360488592 14869088 5779152
21:59:30 77826 8192000000 10272234096 10721910784 1332304352 13.988 98 6 12 0 1347555760 13845376 1406032
22:02:12 55551 8192000000 10280176432 10728370176 1327431584 13.944 97 21 42 0 1346583088 14448912 4702592
22:04:11 122404 8192000000 10298613456 10746056704 1376252960 14.383 100 16 32 0 1394889104 15126032 3510112
22:07:15 32744 8192000000 10299792976 10751430656 1359721136 14.235 98 26 52 0 1374808368 9692768 5394464
22:08:48 49035 8192000000 10305312080 10780377088 1382658016 14.440 99 15 30 2 1412186112 26402384 3125712
22:10:51 38310 8192000000 10301921184 10771935232 1365775776 14.289 98 16 32 0 1388747056 19968048 3003232
22:12:30 143865 8192000000 10291211456 10741608448 1357296528 14.213 97 23 46 0 1371646320 8778160 5571632
22:16:12 26973 8192000000 10272708592 10737004544 1357808400 14.218 98 13 25 2 1374998016 14097520 3092096
22:17:40 67316 8192000000 10285169408 10751680512 1363309024 14.267 98 12 24 1 1377846592 11744752 2792816
22:19:47 48902 8192000000 10280116816 10748313600 1384130480 14.453 100 20 39 0 1399272192 10642048 4499664
22:22:17 72284 8192000000 10258616560 10718699520 1303444576 13.727 95 15 30 0 1314631584 7981312 3205696
22:24:30 91791 8192000000 10262153920 10724429824 1320788992 13.884 97 6 12 0 1337003504 14787984 1426528
22:27:03 68611 8192000000 10255717840 10721026048 1302158064 13.715 95 17 34 0 1316352576 9916576 4277936
22:29:11 25140 8192000000 10273739808 10718588928 1357125520 14.212 100 34 68 0 1376260480 11852144 7282816
22:31:03 55104 8192000000 10262622240 10718322688 1307047968 13.759 96 23 46 0 1324491152 12546928 4896256
22:32:59 43239 8192000000 10278675568 10729578496 1358694896 14.226 100 31 61 0 1383411536 18163200 6553440
22:34:43 62138 8192000000 10301532128 10740318208 1375102496 14.373 100 31 61 1 1394502640 12118592 7281552
22:36:47 86457 8192000000 9389672160 9698717696 502509840 5.779 37 8 16 0 512324512 8438880 1375792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2022 22:38:59 to 12-01-2022 22:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 160 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 22:39:15 to 12-01-2022 22:39:17.

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          246677
MON$OLDEST_ACTIVE               246924
MON$OLDEST_SNAPSHOT             246924
MON$NEXT_TRANSACTION            246924
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                       2050336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
--- end of diff output ---

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

12.01.22 22:39:18. 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-01-12 22:39:20 No severe PSQL-related problems occured
12.01.22 22:39:22. End of report.