Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 01: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 15876216 kB
MemAvailable 31092240 kB
Buffers 0 kB
Cached 15807996 kB
SwapCached 2520 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-07-12 01:38:24
DTS_END                         2022-07-12 01:38:24.9030
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/43888
TRN_ID                          252264
ATT_ID                          654
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: 9720.49 20086 1166459
customer order: creation 187.56 330 22507
customer order: refuse 15.71 366 1885
order to supplier: creation 49.53 1193 5944
order to supplier: removal 3.48 106417 417
invoice (draft): creation 49.23 3230 5907
invoice (draft): removal 53.37 856 6404
invoice accept: apply 45.68 17496 5482
invoice accept: cancel 48.42 924 5810
customer reserve: creation 4204.79 174 504575
customer reserve: removal 4753.52 61 570422
realization accept: apply 92.13 181 11055
realization accept: cancel 76.99 115 9239
payment from customer: creation 64.61 109 7753
payment from customer: removal 5.41 69 649
payment to supplier: creation 52.98 114 6358
payment to supplier: removal 3.65 85 438
service: total inventory turnovers 4.78 1203 574
service: total monetary turnovers 7.80 32 936
service: aggregate perf. data 0.83 18391 100
service: refresh index statistics 0.03 250374 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-07-2022 01:39:01 to 12-07-2022 01:39:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5423
TEST_TIME 2 4580
TEST_TIME 3 5673
TEST_TIME 4 5206
TEST_TIME 5 5585
TEST_TIME 6 5840
TEST_TIME 7 6168
TEST_TIME 8 6136
TEST_TIME 9 6362
TEST_TIME 10 6726
TEST_TIME 11 7029
TEST_TIME 12 7139
TEST_TIME 13 7163
TEST_TIME 14 7297
TEST_TIME 15 7319
TEST_TIME 16 7484
TEST_TIME 17 7641
TEST_TIME 18 7715
TEST_TIME 19 8091
TEST_TIME 20 8112
TEST_TIME 21 8173
TEST_TIME 22 8376
TEST_TIME 23 8489
TEST_TIME 24 8741
TEST_TIME 25 8782
TEST_TIME 26 8973
TEST_TIME 27 9144
TEST_TIME 28 9717
TEST_TIME 29 9599
TEST_TIME 30 10117
TEST_TIME 31 10955
TEST_TIME 32 10834
TEST_TIME 33 10804
TEST_TIME 34 10858
TEST_TIME 35 10625
TEST_TIME 36 10545
TEST_TIME 37 10570
TEST_TIME 38 10422
TEST_TIME 39 10402
TEST_TIME 40 10467
TEST_TIME 41 10416
TEST_TIME 42 10383
TEST_TIME 43 10359
TEST_TIME 44 10351
TEST_TIME 45 10476
TEST_TIME 46 10335
TEST_TIME 47 10208
TEST_TIME 48 10107
TEST_TIME 49 10170
TEST_TIME 50 10019
TEST_TIME 51 10056
TEST_TIME 52 9973
TEST_TIME 53 9957
TEST_TIME 54 10004
TEST_TIME 55 9938
TEST_TIME 56 9875
TEST_TIME 57 9817
TEST_TIME 58 9764
TEST_TIME 59 9690
TEST_TIME 60 9728
TEST_TIME 61 9660
TEST_TIME 62 9745
TEST_TIME 63 9740
TEST_TIME 64 9715
TEST_TIME 65 9866
TEST_TIME 66 9916
TEST_TIME 67 9886
TEST_TIME 68 9959
TEST_TIME 69 9904
TEST_TIME 70 9921
TEST_TIME 71 9896
TEST_TIME 72 10008
TEST_TIME 73 9974
TEST_TIME 74 9995
TEST_TIME 75 9978
TEST_TIME 76 9883
TEST_TIME 77 9822
TEST_TIME 78 9887
TEST_TIME 79 9820
TEST_TIME 80 9812
TEST_TIME 81 9774
TEST_TIME 82 9727
TEST_TIME 83 9677
TEST_TIME 84 9694
TEST_TIME 85 9675
TEST_TIME 86 9688
TEST_TIME 87 9672
TEST_TIME 88 9690
TEST_TIME 89 9718
TEST_TIME 90 9748
TEST_TIME 91 9696
TEST_TIME 92 9820
TEST_TIME 93 9835
TEST_TIME 94 9843
TEST_TIME 95 9881
TEST_TIME 96 9863
TEST_TIME 97 9884
TEST_TIME 98 9941
TEST_TIME 99 9996
TEST_TIME 100 9912
TEST_TIME 101 9878
TEST_TIME 102 9811
TEST_TIME 103 9801
TEST_TIME 104 9780
TEST_TIME 105 9745
TEST_TIME 106 9694
TEST_TIME 107 9697
TEST_TIME 108 9737
TEST_TIME 109 9749
TEST_TIME 110 9761
TEST_TIME 111 9729
TEST_TIME 112 9716
TEST_TIME 113 9772
TEST_TIME 114 9785
TEST_TIME 115 9765
TEST_TIME 116 9744
TEST_TIME 117 9810
TEST_TIME 118 9805
TEST_TIME 119 9790
TEST_TIME 120 9727

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 01:39:05 to 12-07-2022 01:39:08.

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 1633149 1633149 0 0.00 0 15686 6 0 0
doc_list_biud 1633146 1633146 0 0.00 0 36833 27 0 0
sp_add_invoice_to_stock 5629 5482 147 2.61 25 178846 17496 0 147
sp_cancel_adding_invoice 5810 5810 0 0.00 4 88867 925 0 0
sp_cancel_client_order 1885 1885 0 0.00 3 20561 367 0 0
sp_cancel_customer_reserve 570422 570422 0 0.00 0 30518 61 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 2 1602 69 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 5466 86 0 0
sp_cancel_supplier_invoice 6421 6404 17 0.26 2 36847 856 0 17
sp_cancel_supplier_order 417 417 0 0.00 5 1078611 106417 0 0
sp_cancel_write_off 9239 9239 0 0.00 1 25452 116 0 0
sp_client_order 22507 22507 0 0.00 3 25730 330 0 0
sp_customer_reserve 504575 504575 0 0.00 0 87897 174 0 0
sp_fill_shopping_cart 34356 34356 0 0.00 1 9358 34 0 0
sp_fill_shopping_cart_clo_res 504570 504570 0 0.00 0 11506 11 0 0
sp_get_clo_for_invoice 5471 5471 0 0.00 0 23318 226 0 0
sp_kill_cost_storno 16136 16136 0 0.00 0 9276 57 0 0
sp_kill_qstorno_ret_qs2qd 1156904 1156904 0 0.00 0 36815 33 0 0
sp_kill_qty_storno 610703 610703 0 0.00 0 36829 72 0 0
sp_lock_selected_doc 22695 22695 0 0.00 0 8442 5 0 0
sp_make_cost_storno 30637 30637 0 0.00 0 7584 46 0 0
sp_multiply_rows_for_pdistr 30637 30637 0 0.00 0 6078 28 0 0
sp_multiply_rows_for_qdistr 22507 22507 0 0.00 0 10152 133 0 0
sp_pay_from_customer 7753 7753 0 0.00 4 17766 109 0 0
sp_pay_to_supplier 6358 6358 0 0.00 4 11189 115 0 0
sp_payment_common 14111 14111 0 0.00 1 12805 23 0 0
sp_qd_handle_on_cancel_clo 1885 1885 0 0.00 0 20525 340 0 0
sp_qd_handle_on_invoice_upd_sts 11281 11281 0 0.00 0 32891 338 0 0
sp_qd_handle_on_reserve_upd_sts 11055 11055 0 0.00 0 25223 70 0 0
sp_reserve_write_off 11055 11055 0 0.00 6 25283 182 0 0
sp_supplier_invoice 5907 5907 0 0.00 6 84156 3230 0 0
sp_supplier_order 5944 5944 0 0.00 5 57471 1194 0 0
srv_aggregate_perf_data 100 100 0 0.00 3024 153490 18392 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1098 166585 43389 0 0
srv_find_qd_qs_mism 1114329 1114329 0 0.00 0 14440 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 30 94049 1204 0 0
srv_make_money_saldo 936 936 0 0.00 0 3486 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 152744 366019 250374 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5483 5483 0 0.00 0 15677 187 0 0
t$perf-norm:sp_cancel_adding_invoice 467 467 0 0.00 0 2502 80 0 0
t$perf-norm:sp_cancel_client_order 1885 1885 0 0.00 0 1366 13 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 1390 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 564 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 209 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 4904 31 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 24656 1937 0 0
t$perf-norm:sp_cancel_write_off 1115 1115 0 0.00 0 4325 9 0 0
t$perf-norm:sp_client_order 22508 22508 0 0.00 0 9495 18 0 0
t$perf-norm:sp_customer_reserve 1092 1092 0 0.00 0 406 2 0 0
t$perf-norm:sp_pay_from_customer 7753 7753 0 0.00 0 8232 11 0 0
t$perf-norm:sp_pay_to_supplier 6358 6358 0 0.00 0 9551 12 0 0
t$perf-norm:sp_reserve_write_off 11055 11055 0 0.00 0 8217 13 0 0
t$perf-norm:sp_supplier_invoice 5907 5907 0 0.00 0 11364 25 0 0
t$perf-norm:sp_supplier_order 5944 5944 0 0.00 0 3600 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 2438 44 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 107 3 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 2469 33 0 0
t$perf-norm:srv_make_money_saldo 936 936 0 0.00 0 1251 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 3 3 0 0
v_add_invoice_to_stock 11236 10942 294 2.61 0 7241 19 0 294
v_all_customers 40892 40892 0 0.00 0 112 1 0 0
v_all_suppliers 33168 33168 0 0.00 0 26 1 0 0
v_all_wares 45014 45014 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 934 934 0 0.00 0 3835 11 0 0
v_cancel_client_order 3770 3770 0 0.00 0 4297 7 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 1569 6 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 3560 9 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 4951 17 0 0
v_cancel_supplier_invoice 932 898 34 3.64 0 5751 23 0 34
v_cancel_supplier_order 834 834 0 0.00 0 1654 10 0 0
v_cancel_write_off 2230 2230 0 0.00 0 3014 13 0 0
v_max_id_clo_ord 5943 5943 0 0.00 0 604 1 0 0
v_max_id_clo_res 1092 1092 0 0.00 0 2744 18 0 0
v_max_id_ord_sup 5906 5906 0 0.00 0 4279 4 0 0
v_max_non_paid_invoice 1623 1623 0 0.00 0 692 4 0 0
v_max_non_paid_realizn 4150 4150 0 0.00 0 2831 6 0 0
v_min_id_clo_ord 5943 5943 0 0.00 0 2147 6 0 0
v_min_id_clo_res 1092 1092 0 0.00 0 3855 12 0 0
v_min_id_ord_sup 5906 5906 0 0.00 0 4827 5 0 0
v_min_non_paid_invoice 6358 6358 0 0.00 0 10736 15 0 0
v_min_non_paid_realizn 7753 7753 0 0.00 0 12799 17 0 0
v_our_firm 11328 11328 0 0.00 0 18 1 0 0
v_reserve_write_off 22110 22110 0 0.00 0 6253 8 0 0
x_lock_depdocs_on_canc_invoice 11765 11765 0 0.00 0 21102 126 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 9350 371 0 0
x_make_qstorno_1000_1200 5944 5944 0 0.00 1 57461 1124 0 0
x_make_qstorno_1200_2000 5907 5907 0 0.00 3 82875 3101 0 0
x_make_qstorno_2100_3300 504575 504575 0 0.00 0 87897 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 01:39:09 to 12-07-2022 01:39:13.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:29 37242 8192000000 9909553664 10222071808 988850560 10.770 100 89 178 1 1018410960 6105888 23454512
23:11:11 12409 8192000000 9965894656 10288103424 1038977408 11.255 100 91 182 0 1070785616 8583824 23224384
23:12:27 16646 8192000000 9978138096 10313957376 1065388928 11.508 100 77 154 0 1094338288 9959552 18989808
23:13:47 10330 8192000000 10029453264 10378104832 1122484112 12.050 100 72 144 0 1149887072 10721328 16681632
23:15:00 5329 8192000000 10088906272 10446118912 1173112224 12.526 100 80 160 0 1204418432 13134928 18171280
23:16:09 5576 8192000000 10127973536 10494554112 1216443664 12.929 100 86 172 0 1249147328 13855184 18848480
23:17:17 2250 8192000000 10161904944 10541080576 1253516496 13.271 100 84 168 0 1285711552 13393280 18801776
23:18:21 1144 8192000000 10200543184 10582601728 1293240320 13.634 100 85 169 2 1324375072 12368304 18766448
23:19:24 3136 8192000000 10222489984 10607333376 1304350624 13.735 100 80 160 0 1335619808 13669696 17599488
23:20:27 18775 8192000000 10189924784 10606620672 1307734144 13.766 100 22 44 0 1318433792 5475776 5223872
23:21:49 6283 8192000000 10209949952 10623291392 1317486960 13.854 100 25 50 0 1330790192 8125056 5178176
23:22:56 4620 8192000000 10213862656 10628038656 1327133632 13.941 100 23 45 0 1338412896 6705296 4573968
23:24:02 56155 8192000000 10243304288 10651848704 1334722640 14.010 100 43 86 0 1350762864 5004912 11035312
23:25:58 7814 8192000000 10228162000 10649423872 1338977312 14.048 100 32 64 1 1351914320 6313264 6623744
23:27:07 8735 8192000000 10234680304 10657861632 1342298992 14.078 100 17 35 0 1355951152 9750400 3901760
23:28:16 61901 8192000000 10179569920 10594988032 1221701072 12.977 92 31 62 1 1233756304 4459296 7595936
23:30:22 6065 8192000000 10138001088 10532548608 1205014016 12.823 95 41 82 1 1221047392 5882528 10150848
23:31:30 10899 8192000000 10135802560 10530934784 1222867968 12.988 97 28 56 1 1234935248 4829040 7238240
23:32:46 59240 8192000000 10152026976 10557558784 1230709344 13.061 97 30 60 0 1243373184 5221136 7442704
23:34:46 8075 8192000000 10175687120 10581422080 1278890320 13.503 100 29 57 1 1290719760 5346960 6482480
23:35:58 7147 8192000000 10187021664 10600988672 1295933072 13.658 100 29 58 0 1309059456 5622240 7504144
23:37:05 17687 8192000000 10200536672 10614292480 1309479808 13.781 100 29 57 3 1326119520 9591488 7048224
23:38:30 22231 8192000000 10224168800 10637492224 1327122432 13.941 100 36 73 0 1340201312 4770912 8307968
23:39:59 16273 8192000000 10247002816 10664693760 1348574912 14.135 100 39 76 1 1364813808 7614928 8623968
23:41:18 9556 8192000000 10268775568 10685534208 1362717488 14.262 100 43 86 0 1383770848 10768640 10284720
23:42:44 18336 8192000000 10286964032 10707439616 1379181056 14.409 100 48 90 1 1400800000 11258048 10360896
23:44:05 12815 8192000000 10288690624 10718195712 1385766656 14.468 100 36 71 0 1404482688 10360096 8355936
23:45:22 3934 8192000000 10314717968 10733355008 1397711200 14.575 100 60 119 0 1426472992 15016256 13745536
23:46:30 4200 8192000000 10321302336 10745774080 1402016560 14.613 100 57 114 0 1435545088 19626560 13901968
23:47:42 15549 8192000000 10321026384 10745745408 1389278592 14.499 99 47 92 0 1418065376 17051792 11734992
23:49:00 8000 8192000000 10339554384 10764853248 1414504512 14.724 100 61 119 2 1450983536 22281008 14198016
23:50:16 5117 8192000000 10319255520 10739757056 1390667744 14.512 99 50 100 1 1424629968 21951952 12010272
23:51:25 10878 8192000000 10317481680 10738860032 1368978816 14.318 98 50 99 1 1406874128 25136768 12758544
23:52:45 4654 8192000000 10330882464 10743025664 1389621280 14.502 100 81 161 0 1435644416 26933968 19089168
23:53:59 5157 8192000000 10325752528 10744926208 1389884976 14.505 100 57 114 0 1434542064 30102928 14554160
23:55:08 1936 8192000000 10330034768 10736967680 1375450032 14.376 100 85 170 2 1431027024 34243008 21333984
23:56:18 3091 8192000000 10327946064 10740445184 1380730368 14.423 100 65 131 0 1434962608 37183696 17048544
23:57:30 1761 8192000000 10336619312 10741952512 1365602592 14.288 99 82 164 0 1430334784 43547904 21184288
23:58:37 2157 8192000000 10351250000 10763182080 1398124592 14.578 100 81 162 0 1460522640 41950784 20447264
23:59:48 4318 8192000000 10386774288 10800721920 1418756592 14.762 100 76 153 0 1483644896 45283552 19604752
00:00:58 1560 8192000000 10372095360 10778136576 1403129152 14.623 100 86 170 2 1470867904 46760496 20978256
00:02:10 1096 8192000000 10373044480 10782842880 1404770000 14.637 100 79 158 1 1476696416 51084064 20842352
00:03:12 16597 8192000000 10363624752 10791641088 1409320288 14.678 100 39 78 1 1466962784 46350416 11292080
00:04:32 10862 8192000000 10352104144 10782576640 1395452496 14.554 100 38 76 0 1459638224 53459248 10726480
00:05:47 39100 8192000000 10339934096 10784497664 1382231392 14.436 100 34 66 1 1458669168 66023904 10413872
00:07:28 22845 8192000000 10327387280 10763874304 1364058304 14.274 99 38 71 4 1421476528 47633584 9784640
00:08:53 38349 8192000000 10285167152 10769244160 1359965264 14.237 99 13 26 0 1400205872 36353600 3887008
00:10:35 95263 8192000000 10302050624 10752499712 1339462384 14.053 97 11 22 0 1354294512 12514608 2317520
00:13:13 95810 8192000000 10306166080 10757931008 1394015024 14.542 100 41 80 3 1413245504 9353488 9876992
00:16:04 23837 8192000000 10316537968 10749173760 1390004544 14.506 100 56 110 0 1416021968 11175872 14841552
00:17:29 166585 8192000000 10297792704 10758799360 1340724896 14.064 96 6 12 0 1357231376 15177824 1328656
00:21:16 50245 8192000000 10299580112 10747084800 1380659552 14.422 99 18 36 0 1397269152 12752208 3857392
00:23:13 137394 8192000000 10309577216 10754416640 1364659312 14.279 98 22 43 0 1387658208 18617920 4380976
00:26:40 26161 8192000000 10305243264 10735067136 1350141808 14.149 97 60 120 0 1374989728 10847056 14000864
00:28:09 72738 8192000000 10276774080 10733076480 1328024752 13.949 96 18 36 1 1345002384 12912400 4065232
00:30:23 10435 8192000000 10300703456 10734366720 1375539104 14.377 100 72 144 1 1402169472 8860464 17769904
00:31:38 27649 8192000000 10259743280 10710667264 1337634096 14.036 98 27 54 0 1355408176 11060768 6713312
00:33:08 17270 8192000000 10272411888 10713681920 1339862384 14.056 98 29 58 0 1354629856 8313680 6453792
00:34:28 90093 8192000000 10280300704 10737369088 1352321520 14.168 98 14 29 0 1366071344 10384480 3365344
00:36:58 56915 8192000000 10274840544 10729164800 1359115264 14.229 99 20 40 0 1374770848 11412784 4242800
00:39:05 54897 8192000000 10294661392 10749628416 1353093824 14.175 98 9 18 0 1371564464 16831664 1638976
00:41:22 62091 8192000000 10287010288 10728820736 1299345760 13.689 94 21 42 0 1317718576 13363680 5009136
00:43:27 59771 8192000000 10278698912 10746396672 1306501936 13.754 95 9 18 0 1330815376 21971152 2342288
00:45:38 84421 8192000000 10270800544 10725310464 1331811344 13.984 97 18 36 0 1348123504 12273360 4038800
00:48:24 24251 8192000000 10279975888 10710495232 1300107984 13.696 95 39 77 0 1324303120 15670640 8524496
00:49:50 130625 8192000000 10273766208 10718298112 1287575104 13.582 95 23 47 0 1311752416 19212272 4965040
00:53:01 87446 8192000000 10269580800 10718015488 1353736272 14.181 99 24 48 2 1377041872 17738240 5567360
00:55:52 78169 8192000000 10274471888 10736074752 1368887984 14.317 100 24 48 0 1384926576 11317632 4720960
00:58:11 47617 8192000000 10280327536 10740076544 1381213312 14.427 100 28 56 0 1396070336 8424032 6432992
01:00:00 48168 8192000000 10277414448 10737123328 1364114384 14.274 99 13 26 0 1373563984 6902416 2547184
01:01:54 49557 8192000000 10278222592 10750271488 1378538048 14.403 100 11 22 1 1399406640 17320912 3547680
01:04:15 68601 8192000000 10263414928 10722783232 1337225952 14.032 97 16 32 0 1351981904 11275904 3480048
01:06:35 32739 8192000000 10277128064 10718527488 1331716880 13.983 97 21 42 0 1344831984 8441040 4674064
01:08:11 60732 8192000000 10291967376 10754232320 1365052352 14.283 99 19 38 0 1380258416 10998224 4207840
01:10:12 106318 8192000000 10290797904 10748239872 1332604368 13.991 96 9 18 0 1352997296 18688160 1704768
01:13:02 70398 8192000000 10302147968 10748891136 1363763392 14.271 98 11 22 0 1373790336 7825584 2201360
01:15:15 49201 8192000000 10304268720 10752282624 1362297712 14.258 98 30 60 0 1377883040 9134688 6450640
01:17:08 80860 8192000000 10287467504 10749624320 1353642896 14.180 98 15 30 0 1371017072 13862032 3512144
01:19:29 60861 8192000000 10281779584 10736664576 1373601344 14.359 99 15 30 1 1383224848 6446096 3177408
01:21:32 65502 8192000000 10284661376 10747973632 1358443280 14.223 98 21 42 0 1379824560 16418688 4962592
01:24:13 71280 8192000000 10291039904 10747928576 1368058064 14.310 99 18 35 0 1388539536 15765072 4716400
01:26:31 71876 8192000000 10261186880 10725797888 1331367488 13.980 97 13 26 0 1347837792 13196864 3273440
01:28:45 32802 8192000000 10211084400 10655481856 1251965024 13.256 94 14 28 0 1262354096 7658320 2730752
01:30:21 72668 8192000000 10242922464 10705477632 1318766976 13.866 98 12 24 0 1337825744 15782992 3275776
01:32:40 38833 8192000000 10265876960 10711379968 1348762048 14.136 100 27 52 0 1363474352 9443344 5268960
01:34:20 10095 8192000000 10281192320 10722177024 1360959136 14.246 100 48 96 1 1382824272 11704448 10160688
01:35:32 50974 8192000000 10278662528 10727026688 1342950592 14.084 99 16 32 0 1361387456 14980256 3456608
01:37:25 42050 8192000000 10271332400 10708525056 1295494016 13.654 95 32 62 1 1310831136 8676448 6660672

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-07-2022 01:39:15 to 12-07-2022 01:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 01:39:31 to 12-07-2022 01:39:33.

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          250248
MON$OLDEST_ACTIVE               252897
MON$OLDEST_SNAPSHOT             252897
MON$NEXT_TRANSACTION            252897
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                       2055008
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:933: 
:934: mscs-test-161.budzdorov.ru    Tue Jul 12 01:38:27 2022
:935:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:936: 
:937: 
:938: mscs-test-161.budzdorov.ru    Tue Jul 12 01:38:27 2022
:939:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:940: 
--- end of diff output ---

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

12.07.22 01:39:34. 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-07-12 01:39:36 No severe PSQL-related problems occured
12.07.22 01:39:38. End of report.