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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15007436 kB
MemAvailable 30115540 kB
Buffers 0 kB
Cached 16724056 kB
SwapCached 3264 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-11 22:38:31
DTS_END                         2022-12-11 22:38:31.2610
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/37258
TRN_ID                          243017
ATT_ID                          640
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: 9128.08 22237 1095369
customer order: creation 171.65 355 20598
customer order: refuse 14.78 341 1773
order to supplier: creation 46.01 1214 5521
order to supplier: removal 3.35 95132 402
invoice (draft): creation 47.33 3446 5680
invoice (draft): removal 52.63 677 6315
invoice accept: apply 41.21 17811 4945
invoice accept: cancel 47.32 823 5678
customer reserve: creation 3782.42 177 453890
customer reserve: removal 4626.23 55 555148
realization accept: apply 84.43 222 10131
realization accept: cancel 82.53 121 9904
payment from customer: creation 59.26 143 7111
payment from customer: removal 5.63 103 675
payment to supplier: creation 47.70 177 5724
payment to supplier: removal 3.46 92 415
service: total inventory turnovers 4.71 1139 565
service: total monetary turnovers 6.47 40 776
service: aggregate perf. data 0.95 16121 114
service: refresh index statistics 0.03 306550 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2022 22:40:48 to 11-12-2022 22:40:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3416
TEST_TIME 2 2310
TEST_TIME 3 3114
TEST_TIME 4 4019
TEST_TIME 5 4430
TEST_TIME 6 4474
TEST_TIME 7 4668
TEST_TIME 8 4997
TEST_TIME 9 4937
TEST_TIME 10 5626
TEST_TIME 11 5643
TEST_TIME 12 6239
TEST_TIME 13 6176
TEST_TIME 14 6170
TEST_TIME 15 6209
TEST_TIME 16 6536
TEST_TIME 17 6509
TEST_TIME 18 7002
TEST_TIME 19 7390
TEST_TIME 20 7884
TEST_TIME 21 8159
TEST_TIME 22 8220
TEST_TIME 23 8489
TEST_TIME 24 8871
TEST_TIME 25 9056
TEST_TIME 26 9541
TEST_TIME 27 9537
TEST_TIME 28 9604
TEST_TIME 29 9809
TEST_TIME 30 9846
TEST_TIME 31 10341
TEST_TIME 32 10183
TEST_TIME 33 9959
TEST_TIME 34 9751
TEST_TIME 35 9792
TEST_TIME 36 9647
TEST_TIME 37 9597
TEST_TIME 38 9516
TEST_TIME 39 9646
TEST_TIME 40 9542
TEST_TIME 41 9657
TEST_TIME 42 9539
TEST_TIME 43 9415
TEST_TIME 44 9360
TEST_TIME 45 9258
TEST_TIME 46 9292
TEST_TIME 47 9415
TEST_TIME 48 9301
TEST_TIME 49 9332
TEST_TIME 50 9258
TEST_TIME 51 9140
TEST_TIME 52 9084
TEST_TIME 53 9023
TEST_TIME 54 9081
TEST_TIME 55 9029
TEST_TIME 56 9010
TEST_TIME 57 9067
TEST_TIME 58 9091
TEST_TIME 59 9186
TEST_TIME 60 9313
TEST_TIME 61 9335
TEST_TIME 62 9462
TEST_TIME 63 9419
TEST_TIME 64 9402
TEST_TIME 65 9441
TEST_TIME 66 9537
TEST_TIME 67 9585
TEST_TIME 68 9508
TEST_TIME 69 9468
TEST_TIME 70 9513
TEST_TIME 71 9457
TEST_TIME 72 9404
TEST_TIME 73 9445
TEST_TIME 74 9400
TEST_TIME 75 9320
TEST_TIME 76 9281
TEST_TIME 77 9259
TEST_TIME 78 9206
TEST_TIME 79 9162
TEST_TIME 80 9231
TEST_TIME 81 9200
TEST_TIME 82 9204
TEST_TIME 83 9142
TEST_TIME 84 9295
TEST_TIME 85 9319
TEST_TIME 86 9332
TEST_TIME 87 9315
TEST_TIME 88 9351
TEST_TIME 89 9319
TEST_TIME 90 9382
TEST_TIME 91 9412
TEST_TIME 92 9494
TEST_TIME 93 9473
TEST_TIME 94 9401
TEST_TIME 95 9360
TEST_TIME 96 9351
TEST_TIME 97 9315
TEST_TIME 98 9271
TEST_TIME 99 9268
TEST_TIME 100 9260
TEST_TIME 101 9281
TEST_TIME 102 9320
TEST_TIME 103 9292
TEST_TIME 104 9339
TEST_TIME 105 9411
TEST_TIME 106 9395
TEST_TIME 107 9370
TEST_TIME 108 9361
TEST_TIME 109 9384
TEST_TIME 110 9405
TEST_TIME 111 9387
TEST_TIME 112 9353
TEST_TIME 113 9331
TEST_TIME 114 9328
TEST_TIME 115 9313
TEST_TIME 116 9275
TEST_TIME 117 9244
TEST_TIME 118 9218
TEST_TIME 119 9187
TEST_TIME 120 9129

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2022 22:40:52 to 11-12-2022 22:40:55.

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 1516145 1516145 0 0.00 0 36262 7 0 0
doc_list_biud 1516145 1516145 0 0.00 0 27231 26 0 0
sp_add_invoice_to_stock 5017 4945 72 1.43 30 172042 17811 0 72
sp_cancel_adding_invoice 5678 5678 0 0.00 5 50815 824 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 11420 341 0 0
sp_cancel_customer_reserve 555148 555148 0 0.00 0 21370 55 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 16310 103 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 2 5819 92 0 0
sp_cancel_supplier_invoice 6323 6315 8 0.12 2 19531 678 0 8
sp_cancel_supplier_order 402 402 0 0.00 7 1024545 95132 0 0
sp_cancel_write_off 9904 9904 0 0.00 1 24751 122 0 0
sp_client_order 20598 20598 0 0.00 3 28224 356 0 0
sp_customer_reserve 453890 453890 0 0.00 0 56019 178 0 0
sp_fill_shopping_cart 31789 31789 0 0.00 1 15752 40 0 0
sp_fill_shopping_cart_clo_res 453888 453888 0 0.00 0 19553 13 0 0
sp_get_clo_for_invoice 4943 4943 0 0.00 0 22147 320 0 0
sp_kill_cost_storno 16672 16672 0 0.00 0 24711 57 0 0
sp_kill_qstorno_ret_qs2qd 1126917 1126917 0 0.00 0 21215 30 0 0
sp_kill_qty_storno 594294 594294 0 0.00 0 27231 65 0 0
sp_lock_selected_doc 20900 20900 0 0.00 0 9754 8 0 0
sp_make_cost_storno 27909 27909 0 0.00 0 29592 76 0 0
sp_multiply_rows_for_pdistr 27909 27909 0 0.00 0 28105 32 0 0
sp_multiply_rows_for_qdistr 20598 20598 0 0.00 0 14505 146 0 0
sp_pay_from_customer 7111 7111 0 0.00 4 33916 144 0 0
sp_pay_to_supplier 5724 5724 0 0.00 4 34641 177 0 0
sp_payment_common 12835 12835 0 0.00 1 31901 62 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 11388 307 0 0
sp_qd_handle_on_invoice_upd_sts 10621 10621 0 0.00 0 27230 333 0 0
sp_qd_handle_on_reserve_upd_sts 10131 10131 0 0.00 0 20922 67 0 0
sp_reserve_write_off 10131 10131 0 0.00 6 36381 223 0 0
sp_supplier_invoice 5680 5680 0 0.00 6 92936 3447 0 0
sp_supplier_order 5521 5521 0 0.00 4 86205 1215 0 0
srv_aggregate_perf_data 114 114 0 0.00 2418 251187 16121 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1781 112114 43246 0 0
srv_find_qd_qs_mism 1048262 1048262 0 0.00 0 21826 5 0 0
srv_make_invnt_saldo 565 565 0 0.00 37 32844 1140 0 0
srv_make_money_saldo 776 776 0 0.00 1 9497 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 118498 518557 306550 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 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4945 4945 0 0.00 0 12455 172 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 6897 111 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 4143 18 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 4607 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 2690 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 135 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 7160 29 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 22393 1714 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 2267 8 0 0
t$perf-norm:sp_client_order 20598 20598 0 0.00 0 7765 19 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 1710 5 0 0
t$perf-norm:sp_pay_from_customer 7111 7111 0 0.00 0 4609 8 0 0
t$perf-norm:sp_pay_to_supplier 5724 5724 0 0.00 0 6703 11 0 0
t$perf-norm:sp_reserve_write_off 10131 10131 0 0.00 0 7948 10 0 0
t$perf-norm:sp_supplier_invoice 5680 5680 0 0.00 0 6019 21 0 0
t$perf-norm:sp_supplier_order 5521 5521 0 0.00 0 4768 18 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 202 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 974 18 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 2542 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 5 0 0
v_add_invoice_to_stock 10024 9880 144 1.43 0 6931 19 0 144
v_all_customers 37470 37470 0 0.00 0 94 1 0 0
v_all_suppliers 30288 30288 0 0.00 0 31 1 0 0
v_all_wares 41194 41194 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 5598 13 0 0
v_cancel_client_order 3546 3546 0 0.00 0 4595 7 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 1689 5 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2603 6 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 2723 9 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 2183 17 0 16
v_cancel_supplier_order 804 804 0 0.00 0 152 3 0 0
v_cancel_write_off 2114 2114 0 0.00 0 7614 20 0 0
v_max_id_clo_ord 5521 5521 0 0.00 0 523 1 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 4267 19 0 0
v_max_id_ord_sup 5671 5671 0 0.00 0 8549 4 0 0
v_max_non_paid_invoice 1772 1772 0 0.00 0 5559 6 0 0
v_max_non_paid_realizn 3761 3761 0 0.00 0 9967 7 0 0
v_min_id_clo_ord 5521 5521 0 0.00 0 12306 11 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 10152 36 0 0
v_min_id_ord_sup 5671 5671 0 0.00 0 7576 16 0 0
v_min_non_paid_invoice 5724 5724 0 0.00 0 25800 54 0 0
v_min_non_paid_realizn 7111 7111 0 0.00 1 25130 48 0 0
v_our_firm 10424 10424 0 0.00 0 18 1 0 0
v_reserve_write_off 20262 20262 0 0.00 0 5478 6 0 0
x_lock_depdocs_on_canc_invoice 11602 11602 0 0.00 0 13713 118 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 10579 364 0 0
x_make_qstorno_1000_1200 5521 5521 0 0.00 1 86178 1134 0 0
x_make_qstorno_1200_2000 5680 5680 0 0.00 3 92923 3302 0 0
x_make_qstorno_2100_3300 453890 453890 0 0.00 0 56017 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2022 22:40:56 to 11-12-2022 22:41:00.

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:35 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:36 7983 8192000000 9896896768 10206576640 984664000 10.730 100 85 170 0 1012727168 5608336 22454832
20:10:52 20027 8192000000 9952354928 10272534528 1033130480 11.199 100 89 178 0 1063504592 7682928 22691184
20:12:16 13763 8192000000 9978547040 10305867776 1060431232 11.461 100 91 182 0 1091668672 8931216 22306224
20:13:37 6478 8192000000 10026084784 10369204224 1114160720 11.972 100 84 167 0 1144678864 10489152 20028992
20:14:49 4308 8192000000 10082381552 10440183808 1174097824 12.535 100 81 162 0 1204328624 12202800 18028000
20:15:57 2625 8192000000 10123749456 10494922752 1218384752 12.947 100 72 144 0 1246847712 12445824 16017136
20:17:02 2337 8192000000 10160287296 10536624128 1243978176 13.183 100 85 165 0 1276207040 14080000 18148864
20:18:07 2006 8192000000 10185173952 10574782464 1278544592 13.500 100 79 156 1 1308499280 12104096 17850592
20:19:11 2269 8192000000 10215665824 10607955968 1306772416 13.757 100 85 171 0 1338648496 12942800 18933280
20:20:14 19128 8192000000 10192572720 10606145536 1307413792 13.763 100 40 80 0 1324017200 7480336 9123072
20:21:37 5687 8192000000 10201804016 10614554624 1313414192 13.817 100 23 46 1 1324012656 5753536 4844928
20:22:46 7097 8192000000 10199932064 10622373888 1321660352 13.892 100 12 22 0 1330021440 5997520 2363568
20:23:56 15603 8192000000 10240683456 10646650880 1333230880 13.996 100 24 48 0 1344582032 5494544 5856608
20:25:13 11099 8192000000 10229646448 10644504576 1331107456 13.977 100 22 43 0 1341318000 4500960 5709584
20:26:24 7951 8192000000 10226205216 10652606464 1334460848 14.007 100 22 44 1 1349359456 10262512 4636096
20:27:32 14430 8192000000 10218475392 10637443072 1305815968 13.748 98 16 32 0 1318798048 9583472 3398608
20:28:47 29692 8192000000 10161067136 10573656064 1217624384 12.940 93 17 35 0 1226235984 4402112 4209488
20:30:48 7056 8192000000 10152021680 10546401280 1230384240 13.058 98 26 52 0 1245139072 9344208 5410624
20:31:56 8397 8192000000 10137535344 10536509440 1214462032 12.910 96 30 56 1 1231078176 10133632 6482512
20:33:08 17230 8192000000 10163496480 10576351232 1279521856 13.509 100 25 50 1 1291525904 5670576 6333472
20:34:25 28881 8192000000 10175932208 10592931840 1294878960 13.649 100 26 53 0 1306209824 5145584 6185280
20:35:55 7596 8192000000 10190157840 10607239168 1303968784 13.731 100 16 32 0 1313566592 5954240 3643568
20:37:04 6545 8192000000 10211366512 10625228800 1315283328 13.834 100 26 50 0 1330572256 9777792 5511136
20:38:12 11097 8192000000 10229682464 10645094400 1334836912 14.011 100 14 28 0 1342755760 5048496 2870352
20:39:25 112113 8192000000 10256245264 10677145600 1355927440 14.201 100 31 62 0 1369309328 6325936 7055952
20:42:23 28279 8192000000 10281414544 10703077376 1376458192 14.385 100 42 84 0 1398803152 12010352 10334608
20:43:55 7112 8192000000 10295557408 10716307456 1387847440 14.487 100 39 75 1 1407141936 10538080 8756416
20:45:23 38324 8192000000 10314993248 10748289024 1399909328 14.594 100 44 88 0 1429334640 18484112 10941200
20:47:10 6910 8192000000 10328777376 10751946752 1396259312 14.562 99 52 102 0 1429137376 20931504 11946560
20:48:24 4687 8192000000 10341672032 10758324224 1400462640 14.599 99 76 150 1 1443577600 25839984 17274976
20:49:32 4511 8192000000 10338034176 10753687552 1402191232 14.615 100 67 127 2 1445814688 28853712 14769744
20:50:40 5297 8192000000 10326116240 10748030976 1397973360 14.577 100 49 93 0 1438546432 28835520 11737552
20:51:51 4779 8192000000 10325447232 10744598528 1381221056 14.427 98 50 100 1 1424968768 31028144 12719568
20:53:12 3670 8192000000 10332517312 10750275584 1393698896 14.539 100 55 110 0 1444968400 37150128 14119376
20:54:27 7913 8192000000 10325888032 10738700288 1380587136 14.422 100 48 95 0 1432602208 39837184 12177888
20:55:44 1998 8192000000 10335510288 10745274368 1379061136 14.408 100 60 119 0 1438510048 44410224 15038688
20:56:52 6068 8192000000 10327709216 10742513664 1375416240 14.376 100 61 118 2 1433010272 42512544 15081488
20:58:01 17998 8192000000 10299525520 10738044928 1364462384 14.277 100 36 72 1 1418956240 45043424 9450432
20:59:31 1780 8192000000 10323026000 10740850688 1364926944 14.282 99 64 128 1 1418340480 36981552 16431984
21:00:43 7065 8192000000 10318929888 10739515392 1373717760 14.360 100 52 104 0 1427650576 40970400 12962416
21:01:52 11841 8192000000 10311688672 10740047872 1359550256 14.233 99 45 90 0 1409174752 38181136 11443360
21:03:06 33559 8192000000 10289682960 10727137280 1344811456 14.101 98 21 42 0 1382192624 31410608 5970560
21:04:47 40707 8192000000 10287605136 10729123840 1354494976 14.188 99 12 24 0 1382496464 25133248 2868240
21:06:51 35735 8192000000 10285699408 10739601408 1356122240 14.203 99 13 26 0 1378249952 18806272 3321440
21:08:32 8767 8192000000 10308416256 10735587328 1380715104 14.423 100 62 121 0 1411311424 15228144 15368176
21:09:47 70506 8192000000 10296847056 10736070656 1380839696 14.424 100 20 40 0 1395873936 10941232 4093008
21:12:05 106343 8192000000 10279142800 10729488384 1368025152 14.309 99 31 61 1 1387258096 11448832 7784112
21:14:59 109213 8192000000 10298285456 10747138048 1383393008 14.447 99 38 76 0 1407230400 15313696 8523696
21:17:53 54572 8192000000 10306062272 10758721536 1398807440 14.584 100 23 45 0 1416525008 12851680 4865888
21:19:50 44408 8192000000 10306003712 10769264640 1406096800 14.649 100 14 28 0 1419159136 9818896 3243440
21:21:35 57803 8192000000 10324596064 10783055872 1404379888 14.634 99 12 23 0 1424148912 16976496 2792528
21:23:33 61368 8192000000 10313783936 10787164160 1413103696 14.712 100 21 42 1 1438732784 20545936 5083152
21:25:42 39764 8192000000 10304067440 10770464768 1411274720 14.695 100 13 26 0 1424826800 10689056 2863024
21:27:33 29877 8192000000 10301088288 10764378112 1408916464 14.674 100 15 26 0 1417382608 5796400 2669744
21:29:04 60154 8192000000 10291395216 10759356416 1406060032 14.649 100 23 46 1 1417142160 5908992 5173136
21:31:08 72141 8192000000 10296312224 10764849152 1385575584 14.466 98 11 21 1 1399504352 11334528 2594240
21:33:25 28927 8192000000 10260306576 10710843392 1301147904 13.706 93 16 33 0 1310764800 6205152 3411744
21:34:57 78562 8192000000 10285888768 10734608384 1341397120 14.070 97 27 54 0 1362111968 14411536 6303312
21:37:25 75348 8192000000 10287075360 10744315904 1364778960 14.280 99 18 35 0 1383478192 14781376 3917856
21:39:50 28070 8192000000 10283058336 10727174144 1364299664 14.276 99 31 62 3 1381441568 10299744 6842160
21:41:21 35063 8192000000 10273212560 10709843968 1325994720 13.931 97 34 69 0 1342266832 9132464 7139648
21:43:01 14071 8192000000 10293553840 10723987456 1348873456 14.137 99 53 105 0 1377858032 17612848 11371728
21:44:19 63849 8192000000 10252035680 10690457600 1282543072 13.536 95 10 20 0 1306091216 21463840 2084304
21:47:38 48803 8192000000 10251104160 10696433664 1313733120 13.820 97 26 52 0 1331336720 11804000 5799600
21:49:41 31888 8192000000 10257382080 10698461184 1346279856 14.114 100 36 69 0 1363654352 9651296 7723200
21:51:42 61990 8192000000 10253162272 10722267136 1348739904 14.136 99 11 22 0 1366737872 15451840 2546128
21:53:44 70690 8192000000 10260218304 10715582464 1360908256 14.246 100 18 36 1 1374520512 8969824 4642432
21:56:54 77506 8192000000 10263349600 10719326208 1345277200 14.105 98 16 32 0 1363331408 14474768 3579440
21:59:16 33019 8192000000 10264094048 10710540288 1338729216 14.046 98 23 46 1 1350938880 7577376 4632288
22:00:52 60052 8192000000 10284701840 10736926720 1356468832 14.206 98 19 38 0 1377498624 16513968 4515824
22:03:13 99797 8192000000 10280835264 10736840704 1357597184 14.216 98 13 24 0 1373386640 13010224 2779232
22:06:02 61359 8192000000 10295756192 10749476864 1388450464 14.492 100 14 28 0 1403258288 11455696 3352128
22:08:06 79097 8192000000 10310356672 10770108416 1372594176 14.350 98 17 34 0 1386737552 10582912 3560464
22:10:43 110747 8192000000 10306220880 10765455360 1389320464 14.500 99 11 22 0 1404656224 13554464 1781296
22:13:57 4815 8192000000 10315356128 10773176320 1409776224 14.682 100 40 79 0 1427240272 8775648 8688400
22:15:50 63565 8192000000 10322746000 10783072256 1399699856 14.592 99 10 19 0 1417723824 15602384 2421584
22:18:06 41393 8192000000 10315403696 10769321984 1352105088 14.166 96 19 38 0 1372849856 15940128 4804640
22:19:48 43897 8192000000 10293163328 10750398464 1370600448 14.332 99 19 38 1 1391328128 16288704 4438976
22:21:44 20793 8192000000 10302623232 10750230528 1387563712 14.484 100 47 90 0 1416415952 17839840 11012400
22:23:05 53402 8192000000 10263892352 10711117824 1319897264 13.876 96 11 22 0 1333350128 11091552 2361312
22:25:00 31624 8192000000 10275198288 10708549632 1318544400 13.864 96 37 74 1 1335910672 8772256 8594016
22:26:36 15337 8192000000 10262067584 10690658304 1289981424 13.604 95 52 101 0 1311603584 9952688 11669472
22:27:52 47441 8192000000 10254974656 10700652544 1330061280 13.968 99 21 41 0 1347041200 12696688 4283232
22:29:39 93430 8192000000 10261845920 10720456704 1356318128 14.204 100 14 28 1 1371373824 11163984 3891712
22:32:26 45868 8192000000 10239851088 10698870784 1316925616 13.849 97 21 42 0 1331651872 10006992 4719264
22:34:15 51055 8192000000 10218955104 10677702656 1307626256 13.765 98 6 11 1 1318736288 9929584 1180448
22:36:06 57659 8192000000 10240310304 10691342336 1314008096 13.822 98 13 26 0 1322687808 5767168 2912544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2022 22:41:02 to 11-12-2022 22:41:17.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-12-2022 22:41:18 to 11-12-2022 22:41:20.

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          242034
MON$OLDEST_ACTIVE               245114
MON$OLDEST_SNAPSHOT             245114
MON$NEXT_TRANSACTION            245114
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                       2037920
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:132: 
:133: mscs-test-161.budzdorov.ru    Sun Dec 11 22:38:55 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sun Dec 11 22:38:56 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Sun Dec 11 22:38:58 2022
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Sun Dec 11 22:38:58 2022
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Sun Dec 11 22:38:58 2022
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:151: 
--- end of diff output ---

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

11.12.22 22:41:21. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-12-11 22:41:24 No severe PSQL-related problems occured
11.12.22 22:41:25. End of report.