Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.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 16149588 kB
MemAvailable 31063368 kB
Buffers 0 kB
Cached 15537832 kB
SwapCached 2496 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-16 01:38:22
DTS_END                         2022-07-16 01:38:22.4440
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/49488
TRN_ID                          243270
ATT_ID                          729
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: 9242.73 23067 1109128
customer order: creation 173.44 362 20813
customer order: refuse 14.72 377 1766
order to supplier: creation 45.81 1294 5497
order to supplier: removal 3.20 99082 384
invoice (draft): creation 45.89 3507 5507
invoice (draft): removal 53.75 735 6450
invoice accept: apply 42.21 17737 5065
invoice accept: cancel 48.03 788 5763
customer reserve: creation 3838.20 178 460584
customer reserve: removal 4678.27 53 561392
realization accept: apply 84.73 188 10167
realization accept: cancel 82.45 108 9894
payment from customer: creation 60.38 114 7245
payment from customer: removal 5.75 88 690
payment to supplier: creation 49.96 135 5995
payment to supplier: removal 3.65 176 438
service: total inventory turnovers 4.53 1104 544
service: total monetary turnovers 7.01 45 841
service: aggregate perf. data 0.74 23124 89
service: refresh index statistics 0.03 312151 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-07-2022 01:39:07 to 16-07-2022 01:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6130
TEST_TIME 2 4861
TEST_TIME 3 4538
TEST_TIME 4 4921
TEST_TIME 5 4565
TEST_TIME 6 4803
TEST_TIME 7 5068
TEST_TIME 8 5261
TEST_TIME 9 5241
TEST_TIME 10 5691
TEST_TIME 11 6013
TEST_TIME 12 6244
TEST_TIME 13 6696
TEST_TIME 14 6819
TEST_TIME 15 6812
TEST_TIME 16 6837
TEST_TIME 17 7113
TEST_TIME 18 7344
TEST_TIME 19 7458
TEST_TIME 20 7853
TEST_TIME 21 8595
TEST_TIME 22 8564
TEST_TIME 23 8957
TEST_TIME 24 9509
TEST_TIME 25 9581
TEST_TIME 26 9610
TEST_TIME 27 9783
TEST_TIME 28 10225
TEST_TIME 29 10443
TEST_TIME 30 10553
TEST_TIME 31 10215
TEST_TIME 32 10326
TEST_TIME 33 10446
TEST_TIME 34 10343
TEST_TIME 35 10259
TEST_TIME 36 10164
TEST_TIME 37 10024
TEST_TIME 38 10025
TEST_TIME 39 9866
TEST_TIME 40 9694
TEST_TIME 41 9640
TEST_TIME 42 9556
TEST_TIME 43 9637
TEST_TIME 44 9635
TEST_TIME 45 9640
TEST_TIME 46 9527
TEST_TIME 47 9435
TEST_TIME 48 9411
TEST_TIME 49 9383
TEST_TIME 50 9299
TEST_TIME 51 9290
TEST_TIME 52 9396
TEST_TIME 53 9304
TEST_TIME 54 9250
TEST_TIME 55 9222
TEST_TIME 56 9158
TEST_TIME 57 9095
TEST_TIME 58 9015
TEST_TIME 59 8914
TEST_TIME 60 8944
TEST_TIME 61 8985
TEST_TIME 62 8949
TEST_TIME 63 8963
TEST_TIME 64 8934
TEST_TIME 65 9089
TEST_TIME 66 9038
TEST_TIME 67 9141
TEST_TIME 68 9197
TEST_TIME 69 9147
TEST_TIME 70 9202
TEST_TIME 71 9228
TEST_TIME 72 9220
TEST_TIME 73 9174
TEST_TIME 74 9268
TEST_TIME 75 9370
TEST_TIME 76 9286
TEST_TIME 77 9213
TEST_TIME 78 9212
TEST_TIME 79 9206
TEST_TIME 80 9182
TEST_TIME 81 9158
TEST_TIME 82 9150
TEST_TIME 83 9119
TEST_TIME 84 9069
TEST_TIME 85 9003
TEST_TIME 86 9054
TEST_TIME 87 9017
TEST_TIME 88 9023
TEST_TIME 89 9012
TEST_TIME 90 9114
TEST_TIME 91 9135
TEST_TIME 92 9123
TEST_TIME 93 9216
TEST_TIME 94 9245
TEST_TIME 95 9204
TEST_TIME 96 9257
TEST_TIME 97 9310
TEST_TIME 98 9291
TEST_TIME 99 9291
TEST_TIME 100 9310
TEST_TIME 101 9299
TEST_TIME 102 9273
TEST_TIME 103 9226
TEST_TIME 104 9186
TEST_TIME 105 9173
TEST_TIME 106 9141
TEST_TIME 107 9156
TEST_TIME 108 9106
TEST_TIME 109 9071
TEST_TIME 110 9079
TEST_TIME 111 9144
TEST_TIME 112 9152
TEST_TIME 113 9145
TEST_TIME 114 9163
TEST_TIME 115 9160
TEST_TIME 116 9191
TEST_TIME 117 9210
TEST_TIME 118 9217
TEST_TIME 119 9272
TEST_TIME 120 9246

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-07-2022 01:39:11 to 16-07-2022 01:39:14.

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 1535471 1535471 0 0.00 0 27226 6 0 0
doc_list_biud 1535470 1535470 0 0.00 0 45721 25 0 0
sp_add_invoice_to_stock 5125 5065 60 1.17 17 148837 17737 0 60
sp_cancel_adding_invoice 5763 5763 0 0.00 3 54507 788 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 14049 377 0 0
sp_cancel_customer_reserve 561392 561392 0 0.00 0 32693 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 7486 89 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 15217 177 0 0
sp_cancel_supplier_invoice 6457 6450 7 0.10 2 45727 735 0 7
sp_cancel_supplier_order 384 384 0 0.00 9 930705 99082 0 0
sp_cancel_write_off 9894 9894 0 0.00 1 29057 109 0 0
sp_client_order 20813 20813 0 0.00 3 31000 363 0 0
sp_customer_reserve 460584 460584 0 0.00 0 52586 179 0 0
sp_fill_shopping_cart 31816 31816 0 0.00 1 13533 39 0 0
sp_fill_shopping_cart_clo_res 460581 460581 0 0.00 0 15720 14 0 0
sp_get_clo_for_invoice 5060 5060 0 0.00 0 30007 328 0 0
sp_kill_cost_storno 16785 16785 0 0.00 0 21398 51 0 0
sp_kill_qstorno_ret_qs2qd 1139512 1139512 0 0.00 0 45718 29 0 0
sp_kill_qty_storno 600876 600876 0 0.00 0 45720 63 0 0
sp_lock_selected_doc 21025 21025 0 0.00 0 10781 6 0 0
sp_make_cost_storno 28467 28467 0 0.00 0 13571 50 0 0
sp_multiply_rows_for_pdistr 28467 28467 0 0.00 0 9086 24 0 0
sp_multiply_rows_for_qdistr 20813 20813 0 0.00 0 26229 155 0 0
sp_pay_from_customer 7245 7245 0 0.00 4 21257 115 0 0
sp_pay_to_supplier 5995 5995 0 0.00 4 17442 135 0 0
sp_payment_common 13240 13240 0 0.00 2 21238 30 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 14039 336 0 0
sp_qd_handle_on_invoice_upd_sts 10823 10823 0 0.00 0 40852 313 0 0
sp_qd_handle_on_reserve_upd_sts 10167 10167 0 0.00 0 29447 71 0 0
sp_reserve_write_off 10167 10167 0 0.00 6 29479 189 0 0
sp_supplier_invoice 5507 5507 0 0.00 6 88058 3507 0 0
sp_supplier_order 5497 5497 0 0.00 4 31107 1294 0 0
srv_aggregate_perf_data 90 89 1 1.11 2861 323819 23125 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1565 124570 43033 0 0
srv_find_qd_qs_mism 1060991 1060991 0 0.00 0 27222 5 0 0
srv_make_invnt_saldo 544 544 0 0.00 39 42114 1105 0 0
srv_make_money_saldo 841 841 0 0.00 0 11980 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 127919 669617 312151 0 0
t$perf-abend:sp_add_invoice_to_stock 60 60 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 9128 165 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 7647 111 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 6058 18 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 3529 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 5574 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 5235 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 453 453 0 0.00 0 2679 26 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 19802 1568 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 1684 11 0 0
t$perf-norm:sp_client_order 20813 20813 0 0.00 0 6567 16 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 1062 6 0 0
t$perf-norm:sp_pay_from_customer 7245 7245 0 0.00 0 7259 16 0 0
t$perf-norm:sp_pay_to_supplier 5995 5995 0 0.00 0 4847 11 0 0
t$perf-norm:sp_reserve_write_off 10167 10167 0 0.00 0 6205 12 0 0
t$perf-norm:sp_supplier_invoice 5507 5507 0 0.00 0 6681 17 0 0
t$perf-norm:sp_supplier_order 5497 5497 0 0.00 0 6064 19 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 256 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 33 1 0 0
t$perf-norm:srv_make_invnt_saldo 544 544 0 0.00 0 913 18 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 11807 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10240 10120 120 1.17 0 7934 19 0 120
v_all_customers 38350 38350 0 0.00 0 64 1 0 0
v_all_suppliers 30546 30546 0 0.00 0 40 1 0 0
v_all_wares 41626 41626 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 9658 23 0 0
v_cancel_client_order 3532 3532 0 0.00 0 4409 9 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 1818 6 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 7932 23 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 15191 38 0 0
v_cancel_supplier_invoice 920 906 14 1.52 0 4606 24 0 14
v_cancel_supplier_order 768 768 0 0.00 0 5723 16 0 0
v_cancel_write_off 1988 1988 0 0.00 0 5345 21 0 0
v_max_id_clo_ord 5497 5497 0 0.00 0 1979 2 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 2103 15 0 0
v_max_id_ord_sup 5506 5506 0 0.00 0 4898 6 0 0
v_max_non_paid_invoice 1725 1725 0 0.00 0 1796 3 0 0
v_max_non_paid_realizn 3811 3811 0 0.00 0 1197 3 0 0
v_min_id_clo_ord 5497 5497 0 0.00 0 2171 6 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 5688 14 0 0
v_min_id_ord_sup 5506 5506 0 0.00 0 4374 5 0 0
v_min_non_paid_invoice 5995 5995 0 0.00 0 13020 21 0 0
v_min_non_paid_realizn 7245 7245 0 0.00 0 21233 21 0 0
v_our_firm 10144 10144 0 0.00 0 12 1 0 0
v_reserve_write_off 20334 20334 0 0.00 0 17160 12 0 0
x_lock_depdocs_on_canc_invoice 11760 11760 0 0.00 0 14688 112 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 8785 432 0 0
x_make_qstorno_1000_1200 5497 5497 0 0.00 1 31095 1222 0 0
x_make_qstorno_1200_2000 5507 5507 0 0.00 2 79625 3358 0 0
x_make_qstorno_2100_3300 460584 460584 0 0.00 0 52580 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-07-2022 01:39:15 to 16-07-2022 01:39:19.

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:26 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:26 24091 8192000000 9899060176 10206765056 978132016 10.666 100 91 180 1 1007744240 5807216 23805008
23:10:53 18945 8192000000 9933215648 10252742656 1018747600 11.060 100 87 174 0 1049275552 7948224 22579728
23:12:18 14537 8192000000 9959436880 10288857088 1045156736 11.314 100 76 152 0 1073062672 8451392 19454544
23:13:37 11288 8192000000 10029071776 10373361664 1112087776 11.952 100 87 174 0 1143479504 9749472 21642256
23:14:50 5778 8192000000 10077817904 10433105920 1161133392 12.414 100 85 170 0 1193533120 12884576 19515152
23:16:00 3398 8192000000 10118717968 10485968896 1207414480 12.845 100 83 166 1 1239134544 12965232 18754832
23:17:04 2237 8192000000 10146416880 10519031808 1234094272 13.092 100 84 168 0 1266092432 13699216 18298944
23:18:08 1577 8192000000 10186453824 10565750784 1268977600 13.412 100 86 169 0 1302574000 14197472 19398928
23:19:11 1446 8192000000 10211157136 10598862848 1292358016 13.626 100 84 165 2 1323854816 13099264 18397536
23:20:17 880 8192000000 10218091664 10602622976 1303212624 13.724 100 93 187 1 1335081360 11802160 20066576
23:21:18 8391 8192000000 10191169616 10607742976 1306672288 13.756 100 22 44 0 1318635408 7014400 4948720
23:22:30 5339 8192000000 10195976064 10611671040 1316309184 13.843 100 20 40 0 1326482320 5949600 4223536
23:23:37 14621 8192000000 10203754704 10621976576 1319667280 13.874 100 19 36 0 1329796544 5487312 4641952
23:24:55 52198 8192000000 10215378032 10638819328 1325872016 13.930 100 26 52 1 1337342352 5399328 6071008
23:26:51 6667 8192000000 10235058784 10649128960 1332037360 13.986 100 24 48 1 1343595152 6550352 5007440
23:27:59 9345 8192000000 10191294400 10609967104 1300534720 13.700 98 14 28 0 1309309840 5784032 2991088
23:29:10 67039 8192000000 10177636128 10583265280 1187406016 12.659 91 36 71 2 1200388608 4902096 8080496
23:31:21 2055 8192000000 10153274000 10548047872 1248509600 13.225 99 46 92 0 1265991008 6634512 10846896
23:32:25 11051 8192000000 10143534192 10547666944 1233254576 13.084 98 18 37 1 1242785488 5316352 4214560
23:33:36 15219 8192000000 10181775584 10585989120 1280798720 13.520 100 28 52 0 1292718672 5353760 6566192
23:34:52 10030 8192000000 10166573120 10587234304 1284339328 13.553 100 24 50 0 1295946976 6352336 5255312
23:36:03 8140 8192000000 10188786960 10600488960 1297664544 13.674 100 26 52 1 1308982016 5281888 6035584
23:37:12 14040 8192000000 10191076576 10612473856 1307389072 13.762 100 19 38 0 1317013920 5622416 4002432
23:38:28 6707 8192000000 10242820336 10650419200 1334240240 14.005 100 43 78 1 1349704864 6251008 9213616
23:39:39 60513 8192000000 10242979520 10671734784 1346796624 14.119 100 42 83 0 1364740816 8776112 9168080
23:41:46 14687 8192000000 10275656624 10695364608 1364642256 14.279 100 40 80 0 1385546880 11668304 9236320
23:43:05 9606 8192000000 10289809760 10712109056 1378375328 14.402 100 45 88 1 1402705808 13893600 10436880
23:44:17 4085 8192000000 10317748944 10734923776 1390618400 14.511 100 60 119 0 1424418944 20393472 13407072
23:45:43 9852 8192000000 10321670432 10745249792 1391717184 14.521 99 54 105 2 1426782096 22433312 12631600
23:47:02 9283 8192000000 10329529248 10752446464 1398276400 14.580 100 53 105 0 1437060384 26220400 12563584
23:48:20 6322 8192000000 10334206880 10757509120 1393221808 14.535 99 57 112 0 1435224400 28704352 13298240
23:49:31 3228 8192000000 10313497536 10729304064 1367488944 14.305 97 63 123 1 1414033744 31052272 15492528
23:50:41 1565 8192000000 10335898752 10749968384 1381395968 14.429 99 74 146 2 1435521264 36060496 18064800
23:51:46 16969 8192000000 10319766896 10744659968 1381347296 14.429 100 45 90 0 1430510720 38061536 11101888
23:53:18 7417 8192000000 10340428032 10761314304 1387618368 14.485 99 48 96 0 1439731488 39830528 12282592
23:54:31 6214 8192000000 10305347696 10724909056 1362105088 14.256 99 39 78 0 1414045168 41866464 10073616
23:55:45 1909 8192000000 10312324880 10717052928 1349195472 14.140 100 62 123 0 1414464128 49543856 15724800
23:56:52 4389 8192000000 10323241392 10736046080 1368112768 14.310 100 64 126 1 1435231056 50322080 16796208
23:58:08 5724 8192000000 10314362896 10725027840 1352936176 14.174 100 57 112 1 1419547616 51516608 15094832
23:59:16 5115 8192000000 10322460176 10743369728 1363003248 14.264 100 50 100 1 1429398384 52128480 14266656
00:00:29 7045 8192000000 10289603296 10708590592 1343727712 14.091 99 39 78 1 1393999648 39831408 10440528
00:01:41 14259 8192000000 10315399856 10739740672 1365139920 14.283 99 39 76 0 1408879952 34145056 9594976
00:02:57 21467 8192000000 10313689344 10745372672 1371429408 14.340 99 30 58 1 1409246800 30424880 7392512
00:04:26 10325 8192000000 10310696496 10742583296 1370130896 14.328 100 29 57 0 1413121264 35512192 7478176
00:05:48 17816 8192000000 10334367344 10768965632 1385396336 14.465 100 31 61 1 1436613072 42794112 8422624
00:07:12 61570 8192000000 10289286512 10722852864 1346967040 14.120 98 19 38 0 1380945888 29065776 4913072
00:09:17 55254 8192000000 10304740608 10743918592 1376637488 14.386 100 30 60 0 1406409008 21964768 7806752
00:11:24 26414 8192000000 10296142960 10743795712 1388148864 14.489 100 21 43 1 1404761376 12354960 4257552
00:12:55 91047 8192000000 10300481040 10758901760 1393079360 14.533 100 24 49 0 1411751888 12869888 5802640
00:15:27 66489 8192000000 10297628352 10758938624 1395056848 14.551 100 9 16 0 1412784000 15817952 1909200
00:17:40 87321 8192000000 10309258032 10757808128 1397772272 14.575 100 34 68 0 1420296752 14416784 8107696
00:20:32 34837 8192000000 10306085872 10769539072 1399150864 14.587 100 19 38 0 1421037136 16718512 5167760
00:22:25 35718 8192000000 10292506704 10740883456 1380505616 14.421 99 29 57 0 1397110768 9603680 7001472
00:24:05 83498 8192000000 10306787152 10754904064 1374192160 14.365 98 22 44 1 1390734416 11443488 5098768
00:26:28 108141 8192000000 10308007328 10772332544 1399266304 14.588 99 15 29 0 1417385376 14606944 3512128
00:29:34 54596 8192000000 10288842240 10759557120 1393923312 14.541 100 29 58 0 1417735168 17127872 6683984
00:31:42 19765 8192000000 10282946976 10742493184 1372231440 14.347 99 16 32 0 1383770992 7938736 3600816
00:33:03 37826 8192000000 10279867168 10742042624 1375423600 14.376 99 26 52 0 1390753760 9369568 5960592
00:34:53 57656 8192000000 10291960752 10752258048 1389400576 14.501 100 6 12 0 1398179776 7473248 1305952
00:37:26 42259 8192000000 10273424512 10739609600 1370839824 14.335 99 18 36 0 1386588912 10948976 4800112
00:39:17 46164 8192000000 10260161088 10729201664 1344400560 14.097 97 17 34 1 1356103104 8221856 3480688
00:41:41 50705 8192000000 10279313504 10725928960 1350745008 14.154 98 27 54 0 1367499936 11171216 5583712
00:44:31 28912 8192000000 10284072592 10723061760 1349785104 14.146 99 47 92 1 1370033424 10331984 9916336
00:46:00 60083 8192000000 10257511888 10721001472 1322140368 13.896 97 25 51 0 1342936288 15320736 5475184
00:48:00 73411 8192000000 10247450128 10698297344 1295050480 13.650 95 24 48 0 1311652672 11381584 5220608
00:50:13 73841 8192000000 10255502848 10698981376 1284652960 13.555 95 10 20 1 1298537968 11586640 2298368
00:52:38 103744 8192000000 10270474608 10731044864 1323949968 13.912 97 14 28 0 1342412880 15624944 2837968
00:55:22 71154 8192000000 10266948000 10710552576 1341001120 14.066 98 34 66 0 1359870032 10674624 8194288
00:57:55 19956 8192000000 10266347600 10723184640 1361346400 14.249 100 19 38 1 1380110160 13519328 5244432
00:59:16 44485 8192000000 10248435856 10702786560 1350965728 14.156 99 10 20 0 1361938208 8695056 2277424
01:01:05 55537 8192000000 10263308176 10719006720 1356888000 14.209 99 10 20 0 1365690864 6792128 2010736
01:03:01 43906 8192000000 10272574560 10731085824 1375977760 14.381 100 10 19 0 1385145472 7371584 1796128
01:05:01 56812 8192000000 10290568384 10745286656 1378674320 14.405 99 21 42 0 1394612992 10852896 5085776
01:06:58 71684 8192000000 10297945536 10760073216 1375144048 14.373 99 18 36 0 1394973920 15100864 4729008
01:09:11 58243 8192000000 10309994320 10764746752 1402844080 14.620 100 22 44 0 1417964608 10862416 4258112
01:11:16 88352 8192000000 10317875216 10787897344 1411900032 14.701 100 12 24 0 1426815392 12520480 2394880
01:14:07 43843 8192000000 10335742208 10798030848 1422446432 14.794 100 21 42 1 1442843920 15844800 4552688
01:15:53 59360 8192000000 10310832688 10764292096 1340389968 14.061 94 20 40 1 1352408880 7914864 4104048
01:17:57 54591 8192000000 10317304720 10782138368 1395720608 14.557 99 31 61 1 1418961264 15800208 7440448
01:19:58 124570 8192000000 10310957712 10765959168 1396742640 14.566 99 18 36 0 1411381584 10090672 4548272
01:23:07 42752 8192000000 10303829328 10767757312 1393369088 14.536 99 32 63 0 1410738832 9691024 7678720
01:24:52 47074 8192000000 10304915440 10774888448 1398889488 14.585 99 6 12 0 1414139280 13806032 1443760
01:26:40 86054 8192000000 10310002240 10770251776 1387219232 14.481 99 17 34 0 1407188000 16045760 3923008
01:29:40 38256 8192000000 10264126736 10717782016 1345908928 14.111 98 22 44 0 1365868240 14429456 5529856
01:31:22 82567 8192000000 10243491984 10699644928 1260317104 13.333 92 19 38 0 1273945840 9528544 4100192
01:33:45 39612 8192000000 10258772992 10702848000 1339300640 14.051 99 29 58 0 1356764912 10804976 6659296
01:35:26 71865 8192000000 10276219104 10726850560 1320896976 13.885 97 14 28 0 1339718656 15859136 2962544
01:37:40 52574 8192000000 9093421840 9331814400 252428832 2.989 19 2 4 0 257638192 4870112 339248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-07-2022 01:39:21 to 16-07-2022 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 60 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

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

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          243567
MON$OLDEST_ACTIVE               243568
MON$OLDEST_SNAPSHOT             243568
MON$NEXT_TRANSACTION            243568
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                       2022976
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 ---
:1145: 
:1146: mscs-test-161.budzdorov.ru    Sat Jul 16 01:38:36 2022
:1147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1148: 
:1149: 
:1150: mscs-test-161.budzdorov.ru    Sat Jul 16 01:38:36 2022
:1151:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1152: 
--- end of diff output ---

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

16.07.22 01:39:40. 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-16 01:39:42 No severe PSQL-related problems occured
16.07.22 01:39:43. End of report.