Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.04.2022 01: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 16803840 kB
MemAvailable 31585184 kB
Buffers 0 kB
Cached 14950896 kB
SwapCached 3204 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-29 01:38:13
DTS_END                         2022-04-29 01:38:13.9780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45708
TRN_ID                          234692
ATT_ID                          610
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: 8963.76 22656 1075651
customer order: creation 170.43 338 20451
customer order: refuse 15.07 368 1808
order to supplier: creation 44.98 1224 5398
order to supplier: removal 3.45 100556 414
invoice (draft): creation 44.06 3493 5287
invoice (draft): removal 53.00 757 6360
invoice accept: apply 39.55 18187 4746
invoice accept: cancel 47.54 763 5705
customer reserve: creation 3625.94 182 435113
customer reserve: removal 4625.65 59 555078
realization accept: apply 83.10 166 9972
realization accept: cancel 81.92 102 9830
payment from customer: creation 58.53 96 7024
payment from customer: removal 5.76 73 691
payment to supplier: creation 49.18 108 5902
payment to supplier: removal 3.48 87 417
service: total inventory turnovers 4.78 960 573
service: total monetary turnovers 6.52 27 782
service: aggregate perf. data 0.80 17132 96
service: refresh index statistics 0.03 308449 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-04-2022 01:40:52 to 29-04-2022 01:40:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5070
TEST_TIME 2 5795
TEST_TIME 3 5522
TEST_TIME 4 6160
TEST_TIME 5 5745
TEST_TIME 6 5775
TEST_TIME 7 5524
TEST_TIME 8 5380
TEST_TIME 9 5309
TEST_TIME 10 5452
TEST_TIME 11 5568
TEST_TIME 12 5857
TEST_TIME 13 6251
TEST_TIME 14 6289
TEST_TIME 15 6603
TEST_TIME 16 6692
TEST_TIME 17 6822
TEST_TIME 18 7350
TEST_TIME 19 7953
TEST_TIME 20 8378
TEST_TIME 21 8409
TEST_TIME 22 8579
TEST_TIME 23 9139
TEST_TIME 24 9256
TEST_TIME 25 10017
TEST_TIME 26 10095
TEST_TIME 27 10179
TEST_TIME 28 10143
TEST_TIME 29 10014
TEST_TIME 30 9958
TEST_TIME 31 10083
TEST_TIME 32 10076
TEST_TIME 33 9976
TEST_TIME 34 9779
TEST_TIME 35 9827
TEST_TIME 36 9769
TEST_TIME 37 9658
TEST_TIME 38 9586
TEST_TIME 39 9406
TEST_TIME 40 9334
TEST_TIME 41 9278
TEST_TIME 42 9195
TEST_TIME 43 9101
TEST_TIME 44 9029
TEST_TIME 45 9013
TEST_TIME 46 9019
TEST_TIME 47 8999
TEST_TIME 48 8987
TEST_TIME 49 9217
TEST_TIME 50 9228
TEST_TIME 51 9196
TEST_TIME 52 9322
TEST_TIME 53 9268
TEST_TIME 54 9314
TEST_TIME 55 9338
TEST_TIME 56 9289
TEST_TIME 57 9228
TEST_TIME 58 9461
TEST_TIME 59 9401
TEST_TIME 60 9410
TEST_TIME 61 9578
TEST_TIME 62 9590
TEST_TIME 63 9505
TEST_TIME 64 9419
TEST_TIME 65 9315
TEST_TIME 66 9350
TEST_TIME 67 9262
TEST_TIME 68 9170
TEST_TIME 69 9222
TEST_TIME 70 9311
TEST_TIME 71 9234
TEST_TIME 72 9161
TEST_TIME 73 9148
TEST_TIME 74 9087
TEST_TIME 75 9141
TEST_TIME 76 9050
TEST_TIME 77 9052
TEST_TIME 78 9061
TEST_TIME 79 9008
TEST_TIME 80 9010
TEST_TIME 81 9054
TEST_TIME 82 9110
TEST_TIME 83 9117
TEST_TIME 84 9151
TEST_TIME 85 9130
TEST_TIME 86 9198
TEST_TIME 87 9156
TEST_TIME 88 9173
TEST_TIME 89 9182
TEST_TIME 90 9248
TEST_TIME 91 9234
TEST_TIME 92 9142
TEST_TIME 93 9074
TEST_TIME 94 9104
TEST_TIME 95 9155
TEST_TIME 96 9102
TEST_TIME 97 9079
TEST_TIME 98 9060
TEST_TIME 99 9028
TEST_TIME 100 8993
TEST_TIME 101 9034
TEST_TIME 102 9043
TEST_TIME 103 9042
TEST_TIME 104 9143
TEST_TIME 105 9122
TEST_TIME 106 9143
TEST_TIME 107 9176
TEST_TIME 108 9173
TEST_TIME 109 9182
TEST_TIME 110 9172
TEST_TIME 111 9222
TEST_TIME 112 9188
TEST_TIME 113 9125
TEST_TIME 114 9132
TEST_TIME 115 9086
TEST_TIME 116 9059
TEST_TIME 117 9054
TEST_TIME 118 9023
TEST_TIME 119 9029
TEST_TIME 120 8969

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-04-2022 01:40:55 to 29-04-2022 01:40:59.

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 1479527 1479527 0 0.00 0 42853 6 0 0
doc_list_biud 1479527 1479527 0 0.00 0 51265 28 0 0
sp_add_invoice_to_stock 4851 4746 105 2.16 36 133571 18187 0 105
sp_cancel_adding_invoice 5705 5705 0 0.00 5 74651 764 0 0
sp_cancel_client_order 1808 1808 0 0.00 3 31801 368 0 0
sp_cancel_customer_reserve 555078 555078 0 0.00 1 45815 60 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 2 4514 73 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 8925 88 0 0
sp_cancel_supplier_invoice 6370 6360 10 0.15 2 51271 758 0 10
sp_cancel_supplier_order 414 414 0 0.00 6 1024227 100557 0 0
sp_cancel_write_off 9830 9830 0 0.00 1 11647 103 0 0
sp_client_order 20451 20451 0 0.00 3 52809 339 0 0
sp_customer_reserve 435113 435113 0 0.00 0 57024 183 0 0
sp_fill_shopping_cart 31126 31126 0 0.00 1 21101 38 0 0
sp_fill_shopping_cart_clo_res 435108 435108 0 0.00 0 26127 14 0 0
sp_get_clo_for_invoice 4741 4741 0 0.00 0 26533 295 0 0
sp_kill_cost_storno 16643 16643 0 0.00 0 6640 52 0 0
sp_kill_qstorno_ret_qs2qd 1126760 1126760 0 0.00 0 51263 32 0 0
sp_kill_qty_storno 593908 593908 0 0.00 0 51264 70 0 0
sp_lock_selected_doc 20488 20488 0 0.00 0 8919 4 0 0
sp_make_cost_storno 27639 27639 0 0.00 0 42768 49 0 0
sp_multiply_rows_for_pdistr 27639 27639 0 0.00 0 9185 28 0 0
sp_multiply_rows_for_qdistr 20451 20451 0 0.00 0 10978 158 0 0
sp_pay_from_customer 7024 7024 0 0.00 4 13588 97 0 0
sp_pay_to_supplier 5902 5902 0 0.00 4 9210 109 0 0
sp_payment_common 12926 12926 0 0.00 1 13576 20 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 31782 334 0 0
sp_qd_handle_on_invoice_upd_sts 10446 10446 0 0.00 0 31598 328 0 0
sp_qd_handle_on_reserve_upd_sts 9972 9972 0 0.00 0 31638 70 0 0
sp_reserve_write_off 9972 9972 0 0.00 6 31655 167 0 0
sp_supplier_invoice 5287 5287 0 0.00 5 95931 3493 0 0
sp_supplier_order 5398 5398 0 0.00 5 39236 1224 0 0
srv_aggregate_perf_data 97 96 1 1.03 2660 135372 17133 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 3589 117183 46597 0 0
srv_find_qd_qs_mism 1029708 1029708 0 0.00 0 12184 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 31 16317 961 0 0
srv_make_money_saldo 782 782 0 0.00 0 2948 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 148533 388714 308449 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 4746 4746 0 0.00 0 16436 201 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 3052 107 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 8189 25 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 5838 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 1433 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 576 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1815 20 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 26074 1604 0 0
t$perf-norm:sp_cancel_write_off 1021 1021 0 0.00 0 3925 16 0 0
t$perf-norm:sp_client_order 20451 20451 0 0.00 0 11517 21 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 1364 6 0 0
t$perf-norm:sp_pay_from_customer 7024 7024 0 0.00 0 10093 13 0 0
t$perf-norm:sp_pay_to_supplier 5902 5902 0 0.00 0 5067 10 0 0
t$perf-norm:sp_reserve_write_off 9972 9972 0 0.00 0 9246 12 0 0
t$perf-norm:sp_supplier_invoice 5287 5287 0 0.00 0 5122 18 0 0
t$perf-norm:sp_supplier_order 5398 5398 0 0.00 0 11449 20 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 1901 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 10722 183 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 1074 23 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1929 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 352 90 0 0
v_add_invoice_to_stock 9690 9480 210 2.16 0 7793 18 0 210
v_all_customers 37060 37060 0 0.00 0 23 1 0 0
v_all_suppliers 30018 30018 0 0.00 0 38 1 0 0
v_all_wares 40902 40902 0 0.00 0 12 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1017 6 0 0
v_cancel_client_order 3616 3616 0 0.00 0 6323 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 4508 8 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 309 3 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1701 6 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 6837 30 0 20
v_cancel_supplier_order 828 828 0 0.00 0 6649 14 0 0
v_cancel_write_off 2042 2042 0 0.00 0 2706 12 0 0
v_max_id_clo_ord 5398 5398 0 0.00 0 7572 3 0 0
v_max_id_clo_res 957 957 0 0.00 0 3212 12 0 0
v_max_id_ord_sup 5276 5276 0 0.00 0 2108 2 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 2396 4 0 0
v_max_non_paid_realizn 3843 3843 0 0.00 0 963 3 0 0
v_min_id_clo_ord 5398 5398 0 0.00 0 1586 5 0 0
v_min_id_clo_res 957 957 0 0.00 0 4970 13 0 0
v_min_id_ord_sup 5276 5276 0 0.00 0 4694 11 0 0
v_min_non_paid_invoice 5902 5902 0 0.00 0 9189 11 0 0
v_min_non_paid_realizn 7024 7024 0 0.00 0 13568 15 0 0
v_our_firm 10204 10204 0 0.00 0 18 1 0 0
v_reserve_write_off 19944 19944 0 0.00 0 6092 5 0 0
x_lock_depdocs_on_canc_invoice 11686 11686 0 0.00 0 31375 108 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 10991 361 0 0
x_make_qstorno_1000_1200 5398 5398 0 0.00 1 39218 1143 0 0
x_make_qstorno_1200_2000 5287 5287 0 0.00 2 95904 3356 0 0
x_make_qstorno_2100_3300 435113 435113 0 0.00 0 57022 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-04-2022 01:41:00 to 29-04-2022 01:41:04.

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:17 5 8192000000 8801344048 8813514752 6652176 0.081 2 1 2 0 6768688 59104 57408
23:09:18 36949 8192000000 9897890560 10212364288 977507760 10.660 100 89 177 0 1007877552 6179568 24190224
23:11:03 17660 8192000000 9942974000 10266554368 1021461328 11.086 100 84 168 0 1051221056 7828656 21931072
23:12:34 17742 8192000000 9997151696 10335809536 1080387680 11.651 100 82 164 0 1110514512 9559616 20567216
23:13:56 9561 8192000000 10054700224 10409033728 1141430896 12.229 100 79 158 0 1171827008 11747584 18648528
23:15:09 5840 8192000000 10110808688 10478096384 1198707856 12.764 100 79 158 0 1229200640 12588048 17904736
23:16:19 5610 8192000000 10152441840 10526593024 1237591376 13.124 100 78 156 0 1268740816 14068288 17081152
23:17:27 3561 8192000000 10180263856 10564235264 1271422784 13.435 100 86 172 0 1303269360 12224192 19622384
23:18:33 2109 8192000000 10212286304 10596278272 1293870192 13.639 100 88 173 1 1327849408 14706176 19273040
23:19:36 1930 8192000000 10227201168 10612641792 1306116000 13.751 100 89 174 2 1338996352 13534192 19346160
23:20:40 1525 8192000000 10229380880 10617991168 1310780944 13.793 100 88 177 1 1341874496 9897280 21196272
23:21:43 6231 8192000000 10199687344 10613579776 1313416848 13.817 100 27 55 1 1326835152 6688096 6730208
23:22:52 20036 8192000000 10209511712 10635792384 1324974464 13.922 100 27 54 0 1338126560 6766864 6385232
23:24:12 5635 8192000000 10223218624 10643746816 1328687984 13.955 100 16 32 0 1337528176 5475888 3364304
23:25:19 3788 8192000000 10233703840 10650992640 1335218176 14.014 100 33 62 5 1349899088 7110704 7570208
23:26:24 4391 8192000000 10232148480 10654576640 1338908848 14.048 100 33 67 0 1352483424 6164080 7410496
23:27:30 58480 8192000000 10232545120 10646691840 1338070080 14.040 100 26 52 1 1349556208 5275744 6210384
23:29:34 5790 8192000000 10191419056 10606542848 1299920048 13.695 99 19 38 1 1310155360 5857968 4377344
23:30:43 5280 8192000000 10171219552 10577633280 1277688128 13.492 100 25 50 0 1289376048 6017168 5670752
23:31:53 6209 8192000000 10135403232 10535485440 1225722688 13.015 98 20 40 1 1236048416 5307248 5018480
23:32:59 37611 8192000000 10126667936 10529730560 1211831536 12.886 96 38 76 1 1227524144 6305824 9386784
23:34:38 6955 8192000000 10167954480 10576482304 1277934720 13.494 100 37 74 2 1293509200 6895648 8678832
23:35:48 4870 8192000000 10190043248 10597519360 1293141920 13.633 100 31 62 3 1306592272 5649504 7800848
23:36:54 14591 8192000000 10187883920 10603249664 1300111136 13.696 100 18 36 1 1309059792 4924032 4024624
23:38:10 52508 8192000000 10218662720 10634932224 1319026768 13.868 100 26 51 1 1330584944 5783280 5774896
23:40:13 6005 8192000000 10254372816 10665144320 1344282656 14.096 100 49 98 1 1367000640 11402192 11315792
23:41:25 12229 8192000000 10263733264 10685059072 1361793008 14.253 100 40 78 4 1385518672 14598560 9127104
23:42:56 18168 8192000000 10286492496 10708713472 1365436928 14.286 99 32 63 0 1389682784 16206544 8039312
23:44:20 12504 8192000000 10295332640 10721652736 1370999248 14.336 99 44 88 0 1402757760 21482304 10276208
23:45:38 17914 8192000000 10311150928 10737528832 1391695904 14.521 100 40 80 0 1424607984 23214240 9697840
23:47:05 5161 8192000000 10315369328 10729078784 1375997808 14.381 99 65 131 0 1416519920 25022560 15499552
23:48:17 12656 8192000000 10326530848 10747342848 1402740992 14.619 100 57 112 1 1442525360 26077680 13706688
23:49:35 4274 8192000000 10324719424 10736574464 1383642048 14.449 99 68 136 2 1433585584 32999344 16944192
23:50:51 8901 8192000000 10331576288 10748678144 1374983728 14.372 98 61 122 1 1429362480 39134256 15244496
23:52:03 4725 8192000000 10366400464 10787332096 1419993792 14.773 100 54 105 1 1470793424 37123712 13675920
23:53:13 3591 8192000000 10369785392 10780864512 1413560640 14.716 100 80 158 2 1478417280 43609520 21247120
23:54:20 3588 8192000000 10374450384 10783637504 1410392880 14.687 100 88 174 2 1477064080 44301392 22369808
23:55:29 3920 8192000000 10340083904 10747654144 1373902992 14.362 100 75 145 2 1440818784 48245200 18670592
23:56:38 4358 8192000000 10334132768 10747121664 1370596768 14.332 100 73 142 3 1438522736 49418944 18507024
23:57:46 14419 8192000000 10314909120 10740387840 1362928576 14.264 99 46 92 1 1420172192 44028736 13214880
23:59:05 24872 8192000000 10299423264 10733858816 1346825712 14.119 98 24 48 0 1389270096 35423824 7020560
00:00:34 13198 8192000000 10297938320 10735849472 1359577232 14.234 100 30 60 2 1407716656 39515920 8623504
00:01:49 27538 8192000000 10245941456 10675113984 1295679136 13.656 97 20 40 0 1326269664 25298912 5291616
00:03:18 39499 8192000000 10248681392 10693152768 1307026992 13.759 97 16 32 2 1344271472 32915840 4328640
00:05:00 14779 8192000000 10296254224 10719055872 1348858112 14.137 99 56 108 2 1382055472 20375632 12821728
00:06:18 71494 8192000000 10270059728 10721882112 1337377600 14.034 99 12 24 1 1370808576 30095632 3335344
00:09:05 43859 8192000000 10277079504 10713923584 1353617808 14.180 99 26 50 0 1376115904 16661056 5837040
00:11:01 60181 8192000000 10254444288 10710847488 1330584080 13.972 97 16 31 0 1345858624 12116832 3157712
00:13:04 47660 8192000000 10268038816 10725801984 1358657344 14.225 99 10 20 0 1375829456 14809728 2362384
00:14:52 52988 8192000000 10264391744 10706358272 1353080608 14.175 98 16 31 1 1369790160 13047056 3662496
00:16:46 88015 8192000000 10287257920 10731827200 1378095792 14.400 100 20 39 2 1389677824 7279728 4302304
00:19:19 11767 8192000000 10294336256 10744074240 1387967376 14.488 100 53 102 3 1410317680 10496752 11853552
00:20:33 71700 8192000000 10281169488 10738610176 1377762912 14.397 99 16 32 0 1392042528 10025296 4254320
00:23:00 47629 8192000000 10311966368 10758295552 1385561744 14.466 99 37 74 0 1409450304 15024176 8864384
00:24:50 53726 8192000000 10301193680 10758070272 1395189344 14.552 100 18 36 1 1418673536 19289680 4194512
00:26:44 91862 8192000000 10289525120 10771943424 1387306512 14.482 100 19 38 0 1414831776 22767744 4757520
00:29:30 117151 8192000000 10297045024 10750779392 1358300592 14.222 97 17 33 1 1386541728 24453456 3787680
00:32:29 33353 8192000000 10310652528 10767433728 1401675424 14.610 100 27 54 1 1419575936 11650416 6250096
00:34:03 32304 8192000000 10306916512 10754793472 1378499376 14.403 98 22 43 1 1394506192 11232544 4774272
00:35:36 50545 8192000000 10312126688 10770550784 1378334608 14.402 99 25 50 2 1408324032 24024048 5965376
00:37:31 39538 8192000000 10334960112 10769465344 1406023520 14.649 100 35 69 0 1423541168 9826608 7691040
00:39:13 71238 8192000000 10329609232 10785615872 1418139696 14.756 100 11 22 1 1438053760 17258000 2656064
00:41:25 82306 8192000000 10258184608 10703319040 1329595616 13.964 95 14 28 0 1339820208 7244464 2980128
00:43:59 60179 8192000000 10262238048 10725318656 1356768592 14.208 98 24 48 1 1374476208 12251504 5456112
00:46:11 59919 8192000000 10292165824 10753601536 1374776800 14.370 99 12 24 0 1395910208 18057088 3076320
00:48:12 72544 8192000000 10281522000 10742992896 1371608832 14.341 99 8 16 1 1392466416 18491824 2365760
00:50:28 58501 8192000000 10215206000 10655195136 1259794448 13.328 92 20 38 2 1279319648 15037952 4487248
00:52:28 54427 8192000000 10251980560 10695766016 1318262256 13.861 98 16 32 1 1337580944 15421744 3896944
00:54:24 53035 8192000000 10252710672 10706583552 1346458000 14.116 99 13 26 0 1365612496 15428144 3726352
00:56:52 36635 8192000000 10250256512 10693574656 1317091200 13.850 97 23 46 1 1330638192 8409728 5137264
00:58:39 23764 8192000000 10263986320 10696093696 1324530928 13.918 97 43 86 2 1347402208 13273184 9598096
01:00:16 52954 8192000000 10264993360 10709065728 1316610272 13.846 97 9 18 0 1331306544 12802928 1893344
01:02:12 71673 8192000000 10267025360 10709458944 1305041200 13.741 96 23 46 0 1320832640 10870480 4920960
01:04:35 97087 8192000000 10281212144 10708369408 1309082448 13.778 96 25 50 0 1324259088 9942128 5234512
01:07:14 114546 8192000000 10269445808 10713223168 1312526912 13.809 96 14 28 0 1328112576 13070368 2515296
01:10:14 87054 8192000000 10275088800 10722955264 1367331488 14.303 99 18 36 0 1386223776 14714320 4177968
01:12:51 53523 8192000000 10274199952 10751528960 1378495600 14.403 100 12 24 1 1396266752 15371600 2399552
01:14:53 59418 8192000000 10276628256 10739044352 1372878576 14.353 99 7 14 2 1383150352 8544848 1726928
01:17:01 47860 8192000000 10291659680 10745192448 1373636592 14.360 99 10 20 0 1384910624 9234752 2039280
01:18:50 47173 8192000000 10313799168 10769342464 1391841056 14.522 99 20 40 0 1410545008 14032080 4671872
01:20:45 62824 8192000000 10313338112 10767552512 1389945488 14.505 99 20 39 1 1412793040 18247984 4599568
01:22:49 63531 8192000000 10303948704 10760089600 1381846336 14.433 99 19 38 0 1395497056 9694352 3956368
01:24:53 67494 8192000000 10319843840 10784464896 1407052928 14.658 100 12 24 1 1420339056 10688896 2597232
01:27:06 58235 8192000000 10320219856 10766532608 1380840576 14.424 98 14 28 0 1393096128 9165664 3089888
01:29:12 73578 8192000000 10297561744 10755379200 1362518160 14.260 97 14 28 0 1378822096 13286688 3017248
01:31:27 116975 8192000000 10266605040 10720780288 1292552416 13.627 93 17 34 1 1304267104 7827936 3886752
01:34:25 65907 8192000000 10263514720 10713808896 1338983488 14.048 98 12 25 0 1350736464 9009232 2743744
01:37:38 51057 8192000000 9400477760 9713573888 554449488 6.339 41 10 20 0 565023488 8350720 2223280

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-04-2022 01:41:06 to 29-04-2022 01:41:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-04-2022 01:41:21 to 29-04-2022 01:41:23.

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          234500
MON$OLDEST_ACTIVE               234936
MON$OLDEST_SNAPSHOT             234936
MON$NEXT_TRANSACTION            234936
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                       2013760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 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 ---
:161: 
:162: mscs-test-161.budzdorov.ru    Fri Apr 29 01:38:33 2022
:163:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:164: 
:165: 
:166: mscs-test-161.budzdorov.ru    Fri Apr 29 01:38:36 2022
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Fri Apr 29 01:38:36 2022
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:172: 
--- end of diff output ---

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

29.04.22 01:41:24. 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-04-29 01:41:27 No severe PSQL-related problems occured
29.04.22 01:41:28. End of report.