Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.04.2022 16: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 16327200 kB
MemAvailable 31523496 kB
Buffers 0 kB
Cached 15355120 kB
SwapCached 2636 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-22 16:38:15
DTS_END                         2022-04-22 16:38:15.4320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33526
TRN_ID                          248330
ATT_ID                          704
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: 9692.96 21740 1163155
customer order: creation 178.49 346 21419
customer order: refuse 15.79 326 1895
order to supplier: creation 48.34 1266 5801
order to supplier: removal 3.68 103054 442
invoice (draft): creation 46.69 3343 5603
invoice (draft): removal 55.92 848 6710
invoice accept: apply 43.19 17803 5183
invoice accept: cancel 50.86 709 6103
customer reserve: creation 3960.48 177 475258
customer reserve: removal 4971.56 59 596587
realization accept: apply 87.48 169 10497
realization accept: cancel 92.98 108 11157
payment from customer: creation 62.39 107 7487
payment from customer: removal 5.79 73 695
payment to supplier: creation 52.68 132 6321
payment to supplier: removal 3.73 74 447
service: total inventory turnovers 4.91 1353 589
service: total monetary turnovers 7.01 38 841
service: aggregate perf. data 0.97 17816 116
service: refresh index statistics 0.03 286992 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-04-2022 16:39:11 to 22-04-2022 16:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6041
TEST_TIME 2 6544
TEST_TIME 3 5693
TEST_TIME 4 5863
TEST_TIME 5 5628
TEST_TIME 6 6160
TEST_TIME 7 6212
TEST_TIME 8 6117
TEST_TIME 9 6374
TEST_TIME 10 6919
TEST_TIME 11 6879
TEST_TIME 12 7428
TEST_TIME 13 7177
TEST_TIME 14 7523
TEST_TIME 15 7576
TEST_TIME 16 7662
TEST_TIME 17 7736
TEST_TIME 18 7637
TEST_TIME 19 7990
TEST_TIME 20 7996
TEST_TIME 21 8213
TEST_TIME 22 8582
TEST_TIME 23 8870
TEST_TIME 24 9658
TEST_TIME 25 9637
TEST_TIME 26 9877
TEST_TIME 27 10085
TEST_TIME 28 10309
TEST_TIME 29 11121
TEST_TIME 30 11183
TEST_TIME 31 11135
TEST_TIME 32 11346
TEST_TIME 33 11353
TEST_TIME 34 11290
TEST_TIME 35 11530
TEST_TIME 36 11379
TEST_TIME 37 11316
TEST_TIME 38 11142
TEST_TIME 39 11149
TEST_TIME 40 10930
TEST_TIME 41 10828
TEST_TIME 42 10749
TEST_TIME 43 10759
TEST_TIME 44 10685
TEST_TIME 45 10565
TEST_TIME 46 10400
TEST_TIME 47 10498
TEST_TIME 48 10430
TEST_TIME 49 10368
TEST_TIME 50 10412
TEST_TIME 51 10559
TEST_TIME 52 10451
TEST_TIME 53 10457
TEST_TIME 54 10425
TEST_TIME 55 10274
TEST_TIME 56 10247
TEST_TIME 57 10284
TEST_TIME 58 10202
TEST_TIME 59 10132
TEST_TIME 60 10111
TEST_TIME 61 10036
TEST_TIME 62 9954
TEST_TIME 63 9861
TEST_TIME 64 9971
TEST_TIME 65 10041
TEST_TIME 66 10004
TEST_TIME 67 10040
TEST_TIME 68 10044
TEST_TIME 69 10095
TEST_TIME 70 10039
TEST_TIME 71 10060
TEST_TIME 72 10015
TEST_TIME 73 10131
TEST_TIME 74 10167
TEST_TIME 75 10175
TEST_TIME 76 10183
TEST_TIME 77 10147
TEST_TIME 78 10162
TEST_TIME 79 10076
TEST_TIME 80 10050
TEST_TIME 81 9987
TEST_TIME 82 9968
TEST_TIME 83 9977
TEST_TIME 84 9929
TEST_TIME 85 9872
TEST_TIME 86 9862
TEST_TIME 87 9804
TEST_TIME 88 9766
TEST_TIME 89 9711
TEST_TIME 90 9775
TEST_TIME 91 9762
TEST_TIME 92 9754
TEST_TIME 93 9807
TEST_TIME 94 9819
TEST_TIME 95 9866
TEST_TIME 96 9897
TEST_TIME 97 9887
TEST_TIME 98 9887
TEST_TIME 99 9919
TEST_TIME 100 9969
TEST_TIME 101 9918
TEST_TIME 102 9990
TEST_TIME 103 9944
TEST_TIME 104 9866
TEST_TIME 105 9824
TEST_TIME 106 9800
TEST_TIME 107 9785
TEST_TIME 108 9765
TEST_TIME 109 9733
TEST_TIME 110 9701
TEST_TIME 111 9694
TEST_TIME 112 9712
TEST_TIME 113 9691
TEST_TIME 114 9684
TEST_TIME 115 9699
TEST_TIME 116 9660
TEST_TIME 117 9688
TEST_TIME 118 9733
TEST_TIME 119 9757
TEST_TIME 120 9700

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-04-2022 16:39:15 to 22-04-2022 16:39:18.

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 1604486 1604486 0 0.00 0 15224 6 0 0
doc_list_biud 1604483 1604483 0 0.00 0 42803 28 0 0
sp_add_invoice_to_stock 5300 5183 117 2.20 22 165047 17804 0 117
sp_cancel_adding_invoice 6103 6103 0 0.00 5 58223 710 0 0
sp_cancel_client_order 1895 1895 0 0.00 4 16319 327 0 0
sp_cancel_customer_reserve 596587 596587 0 0.00 1 17469 59 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 2244 73 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 2 3522 74 0 0
sp_cancel_supplier_invoice 6722 6710 12 0.17 2 42807 848 0 12
sp_cancel_supplier_order 442 442 0 0.00 9 1115611 103055 0 0
sp_cancel_write_off 11157 11157 0 0.00 1 10880 108 0 0
sp_client_order 21419 21419 0 0.00 3 54805 347 0 0
sp_customer_reserve 475258 475258 0 0.00 0 96851 177 0 0
sp_fill_shopping_cart 32821 32821 0 0.00 1 19418 39 0 0
sp_fill_shopping_cart_clo_res 475244 475244 0 0.00 0 14493 13 0 0
sp_get_clo_for_invoice 5169 5169 0 0.00 0 39144 353 0 0
sp_kill_cost_storno 18402 18402 0 0.00 0 10878 54 0 0
sp_kill_qstorno_ret_qs2qd 1211483 1211483 0 0.00 0 42802 32 0 0
sp_kill_qty_storno 638560 638560 0 0.00 0 42802 70 0 0
sp_lock_selected_doc 21708 21708 0 0.00 0 6738 5 0 0
sp_make_cost_storno 29474 29474 0 0.00 0 13629 48 0 0
sp_multiply_rows_for_pdistr 29474 29474 0 0.00 0 6815 29 0 0
sp_multiply_rows_for_qdistr 21419 21419 0 0.00 0 54796 168 0 0
sp_pay_from_customer 7487 7487 0 0.00 4 11884 108 0 0
sp_pay_to_supplier 6321 6321 0 0.00 5 16137 132 0 0
sp_payment_common 13808 13808 0 0.00 2 11629 26 0 0
sp_qd_handle_on_cancel_clo 1895 1895 0 0.00 0 16312 306 0 0
sp_qd_handle_on_invoice_upd_sts 11272 11272 0 0.00 0 13534 323 0 0
sp_qd_handle_on_reserve_upd_sts 10497 10497 0 0.00 0 14977 62 0 0
sp_reserve_write_off 10497 10497 0 0.00 7 14992 170 0 0
sp_supplier_invoice 5603 5603 0 0.00 6 64457 3344 0 0
sp_supplier_order 5801 5801 0 0.00 6 22188 1266 0 0
srv_aggregate_perf_data 117 116 1 0.85 3218 170265 17816 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1850 131461 44480 0 0
srv_find_qd_qs_mism 1114053 1114053 0 0.00 0 15223 5 0 0
srv_make_invnt_saldo 589 589 0 0.00 28 129835 1353 0 0
srv_make_money_saldo 841 841 0 0.00 1 12132 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 154694 454600 286993 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5185 5185 0 0.00 0 8108 177 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 10647 120 0 0
t$perf-norm:sp_cancel_client_order 1895 1895 0 0.00 0 1904 11 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 2704 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 2537 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 965 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 4787 33 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 27188 1692 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 6248 10 0 0
t$perf-norm:sp_client_order 21419 21419 0 0.00 0 14481 19 0 0
t$perf-norm:sp_customer_reserve 1096 1096 0 0.00 0 574 3 0 0
t$perf-norm:sp_pay_from_customer 7487 7487 0 0.00 0 6112 10 0 0
t$perf-norm:sp_pay_to_supplier 6321 6321 0 0.00 0 11049 11 0 0
t$perf-norm:sp_reserve_write_off 10497 10497 0 0.00 0 11769 16 0 0
t$perf-norm:sp_supplier_invoice 5603 5603 0 0.00 0 6896 19 0 0
t$perf-norm:sp_supplier_order 5801 5801 0 0.00 0 6786 18 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 197 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 2135 19 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 4851 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 22 8 0 0
v_add_invoice_to_stock 10572 10338 234 2.21 0 4789 17 0 234
v_all_customers 39146 39146 0 0.00 0 23 1 0 0
v_all_suppliers 32378 32378 0 0.00 0 265 1 0 0
v_all_wares 42838 42838 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1307 7 0 0
v_cancel_client_order 3790 3790 0 0.00 0 3763 5 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 2058 5 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2146 6 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 1857 6 0 0
v_cancel_supplier_invoice 852 828 24 2.81 0 3055 17 0 24
v_cancel_supplier_order 884 884 0 0.00 0 192 4 0 0
v_cancel_write_off 2112 2112 0 0.00 0 4102 17 0 0
v_max_id_clo_ord 5801 5801 0 0.00 0 3974 2 0 0
v_max_id_clo_res 1096 1096 0 0.00 0 3737 24 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 3639 5 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 1142 4 0 0
v_max_non_paid_realizn 3880 3880 0 0.00 0 2713 4 0 0
v_min_id_clo_ord 5801 5801 0 0.00 0 2883 6 0 0
v_min_id_clo_res 1096 1096 0 0.00 0 5169 12 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 5135 5 0 0
v_min_non_paid_invoice 6321 6321 0 0.00 0 10383 17 0 0
v_min_non_paid_realizn 7487 7487 0 0.00 0 11620 19 0 0
v_our_firm 10906 10906 0 0.00 0 14 1 0 0
v_reserve_write_off 20994 20994 0 0.00 0 4396 6 0 0
x_lock_depdocs_on_canc_invoice 12399 12399 0 0.00 0 17948 124 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 7553 346 0 0
x_make_qstorno_1000_1200 5801 5801 0 0.00 1 22174 1178 0 0
x_make_qstorno_1200_2000 5603 5603 0 0.00 3 64378 3207 0 0
x_make_qstorno_2100_3300 475258 475258 0 0.00 0 96850 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-04-2022 16:39:19 to 22-04-2022 16:39:23.

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
14:08:19 4 8192000000 8801913456 8814235648 7939312 0.096 2 2 3 0 8080416 66144 74960
14:09:19 32878 8192000000 9917304464 10226778112 994335584 10.824 100 87 174 0 1023025872 6535872 22154416
14:11:04 23357 8192000000 9956597376 10279624704 1037191120 11.238 100 91 182 0 1070216304 9507792 23517392
14:12:45 20717 8192000000 10001264848 10336468992 1079540336 11.643 100 81 162 0 1109810784 10760208 19510240
14:14:08 12641 8192000000 10045763424 10391879680 1129713408 12.119 100 86 174 1 1161556480 12054928 19788144
14:15:23 9261 8192000000 10109099008 10471845888 1188718608 12.671 100 82 164 1 1219817216 11895584 19203024
14:16:34 4382 8192000000 10148251680 10524049408 1239640112 13.143 100 79 157 1 1270033632 12608064 17785456
14:17:41 3672 8192000000 10180660928 10558709760 1265859952 13.384 100 83 167 0 1297247984 13242032 18146000
14:18:46 1601 8192000000 10222961632 10604871680 1302243968 13.716 100 95 191 1 1338083152 14216400 21622784
14:19:48 12257 8192000000 10195485616 10606190592 1308276448 13.770 100 45 90 2 1327786288 8804800 10705040
14:21:01 10424 8192000000 10204205120 10619498496 1317343568 13.853 100 20 41 0 1329074672 7070096 4661008
14:22:12 5063 8192000000 10206476016 10624098304 1326112736 13.932 100 21 41 2 1336845504 6094928 4637840
14:23:19 8103 8192000000 10231472320 10633158656 1335379760 14.016 100 30 60 2 1348628208 6750672 6497776
14:24:30 18316 8192000000 10232586224 10648694784 1334985328 14.012 100 43 87 0 1352516992 7835264 9696400
14:25:51 6879 8192000000 10235836224 10653368320 1337702000 14.037 100 23 46 0 1348993168 6181824 5109344
14:26:59 7066 8192000000 10229932848 10650492928 1340282640 14.060 100 18 36 0 1349711856 5654736 3774480
14:28:11 56840 8192000000 10204791264 10596843520 1259732592 13.328 97 56 113 1 1278306816 5328864 13245360
14:30:09 5292 8192000000 10155214752 10544975872 1199612672 12.773 94 30 58 3 1211837664 5273648 6951344
14:31:15 3944 8192000000 10131506448 10522677248 1219504992 12.957 98 39 78 0 1234330336 5446128 9379216
14:32:21 47833 8192000000 10149153808 10561015808 1263266160 13.360 100 33 64 2 1277045888 5706144 8073584
14:34:10 5192 8192000000 10180402640 10586411008 1283425328 13.544 100 39 76 1 1299613936 7542000 8646608
14:35:16 10511 8192000000 10181468944 10595049472 1290945840 13.613 100 21 42 1 1305496672 9822672 4728160
14:36:26 8887 8192000000 10192956544 10605973504 1303803088 13.730 100 22 44 0 1315046992 6087536 5156368
14:37:42 14215 8192000000 10195642240 10612232192 1305807504 13.748 100 13 26 0 1318793904 9830528 3155872
14:39:02 7737 8192000000 10240890320 10653638656 1337042816 14.031 100 59 113 2 1358646224 9211776 12391632
14:40:13 38643 8192000000 10247764432 10671226880 1351890048 14.164 100 38 76 0 1370053008 9687184 8475776
14:41:54 22212 8192000000 10283306272 10700836864 1367970560 14.309 100 52 102 1 1394400336 13812464 12617312
14:43:27 18141 8192000000 10293000928 10714128384 1379948912 14.416 100 51 103 1 1408172528 15658400 12565216
14:44:46 16995 8192000000 10289916736 10718195712 1381165696 14.427 100 46 91 2 1405217504 13809504 10242304
14:46:11 7566 8192000000 10310453792 10726436864 1384115856 14.453 100 65 129 1 1423035776 24108512 14811408
14:47:20 15486 8192000000 10334203200 10757103616 1401480816 14.608 100 55 110 1 1443357504 28533072 13343616
14:48:41 7248 8192000000 10326018016 10751463424 1391057936 14.515 99 55 108 0 1431978864 26695936 14224992
14:49:53 2676 8192000000 10357689904 10766876672 1403984208 14.630 100 83 165 2 1458646304 33796912 20865184
14:51:00 6882 8192000000 10331428688 10746003456 1374731328 14.369 98 54 106 2 1417663520 29395376 13536816
14:52:10 1848 8192000000 10337350592 10761732096 1397654560 14.574 100 65 127 2 1451250160 36872320 16723280
14:53:17 3217 8192000000 10300685056 10715009024 1351743472 14.163 98 66 130 5 1399827600 32191872 15892256
14:54:27 4402 8192000000 10331933472 10747138048 1377241744 14.392 99 68 134 0 1434131728 40500768 16389216
14:55:35 3326 8192000000 10360343536 10754686976 1388473696 14.492 100 89 178 1 1456645008 45465824 22705488
14:56:48 2765 8192000000 10368365120 10766884864 1395063936 14.551 100 82 160 0 1466885040 51773264 20047840
14:57:54 10323 8192000000 10365954944 10784153600 1403694960 14.628 100 46 92 0 1469365520 52909840 12760720
14:59:08 4134 8192000000 10360374384 10786312192 1397874528 14.576 100 67 133 1 1475314736 60296080 17144128
15:00:22 4503 8192000000 10373980832 10796089344 1400578272 14.600 100 57 114 0 1477536192 61487264 15470656
15:01:30 13393 8192000000 10364004496 10791563264 1395906544 14.559 100 41 82 2 1476064848 68393568 11764736
15:03:15 18980 8192000000 10325631616 10760388608 1368434464 14.313 99 22 44 2 1420930336 46315264 6180608
15:04:46 29933 8192000000 10302837456 10744778752 1347933296 14.129 99 24 46 1 1402821680 47704576 7183808
15:06:20 23594 8192000000 10309051536 10755096576 1350786512 14.155 99 24 49 0 1413335712 55825728 6723472
15:07:58 89837 8192000000 10308161152 10746634240 1362958896 14.264 99 20 40 0 1385327888 17870272 4498720
15:10:30 60783 8192000000 10289010768 10747105280 1336293280 14.024 97 8 17 1 1354642960 16733952 1615728
15:12:31 99654 8192000000 10301990304 10766282752 1386727184 14.477 100 7 14 0 1408848320 20643536 1477600
15:15:12 94780 8192000000 10291511712 10732855296 1335020736 14.012 96 22 44 0 1354040448 13753184 5266528
15:17:47 104144 8192000000 10322669712 10754220032 1389160720 14.498 100 45 90 1 1407367088 7800576 10405792
15:20:33 57080 8192000000 10307695600 10759512064 1383475552 14.448 99 39 78 1 1405208320 12767264 8965504
15:22:33 69126 8192000000 10303701280 10760257536 1394365680 14.545 100 19 38 0 1417647232 18317312 4964240
15:24:48 15316 8192000000 10304848096 10744619008 1371036128 14.336 98 32 65 1 1397209904 17983008 8190768
15:26:04 35976 8192000000 10314040480 10762219520 1396503632 14.564 100 29 58 0 1421499696 18197792 6798272
15:27:42 54499 8192000000 10324318896 10787811328 1411364560 14.696 100 22 44 0 1440836208 23258880 6212768
15:29:47 29912 8192000000 10267094736 10717126656 1333527712 13.999 96 15 30 1 1347877232 11165008 3184512
15:31:19 90195 8192000000 10288163968 10748239872 1368235632 14.311 99 18 36 2 1388077808 15093776 4748400
15:33:56 86375 8192000000 10301437888 10769825792 1390101056 14.507 100 40 80 3 1411975264 12416832 9457376
15:36:29 30169 8192000000 10298414944 10751037440 1388847568 14.496 100 34 70 1 1403649792 7263424 7538800
15:38:00 59354 8192000000 10272227936 10732838912 1351324368 14.159 98 10 20 0 1364649584 11258464 2066752
15:40:17 66191 8192000000 10259251184 10714480640 1325633264 13.928 97 16 32 0 1343917168 14514368 3769536
15:42:23 40025 8192000000 10255503248 10723082240 1349878240 14.146 100 6 12 0 1365317200 14023696 1415264
15:44:04 53453 8192000000 10270645568 10729881600 1357150528 14.212 99 9 16 3 1370726912 11880000 1696384
15:45:58 60776 8192000000 10273415728 10736201728 1352276496 14.168 98 16 32 0 1367301008 11481632 3542880
15:48:05 30166 8192000000 10270400992 10720686080 1338316384 14.042 98 15 30 3 1352528160 10545888 3665888
15:49:37 23939 8192000000 10281444384 10719379456 1339695472 14.055 98 43 87 1 1361165760 12093104 9377184
15:51:40 72780 8192000000 10285433008 10735452160 1358456384 14.223 99 16 31 1 1383124480 20712592 3955504
15:53:53 27051 8192000000 10281205568 10725122048 1331811632 13.984 97 21 42 1 1350406608 14076224 4518752
15:55:26 27083 8192000000 10286470784 10724143104 1354591728 14.189 99 28 56 0 1372764080 11651744 6520608
15:57:00 94167 8192000000 10276213488 10724352000 1353863648 14.182 99 35 70 0 1371019040 9248656 7906736
15:59:36 29176 8192000000 10294704192 10734419968 1378520144 14.403 100 48 96 1 1399447472 10335968 10591360
16:01:06 131460 8192000000 10284058464 10754211840 1361500144 14.251 98 5 10 0 1376552352 13951440 1100768
16:04:18 88100 8192000000 10281856272 10753581056 1395282192 14.553 100 7 14 0 1408459232 11644496 1532544
16:06:54 70037 8192000000 10294266416 10754297856 1367397232 14.304 98 10 20 0 1383284672 13550080 2337360
16:09:04 46592 8192000000 10303062432 10761207808 1389488544 14.501 99 21 42 1 1402991984 8847280 4656160
16:10:52 44229 8192000000 10319446720 10785390592 1405671792 14.645 100 16 32 0 1424533856 15144560 3717504
16:12:41 102421 8192000000 10304261680 10778931200 1349461088 14.143 96 13 26 0 1372709264 20282352 2965824
16:15:35 22066 8192000000 10306267360 10761228288 1394111024 14.543 100 39 76 2 1415286224 11715888 9459312
16:16:57 59307 8192000000 10284732448 10749382656 1346232448 14.114 97 27 54 1 1371154320 18610096 6311776
16:18:57 78682 8192000000 10303877792 10744262656 1322140144 13.896 95 26 52 2 1337943984 9780064 6023776
16:21:19 118299 8192000000 10267501344 10722623488 1324981424 13.922 95 33 66 0 1339773040 7231056 7560560
16:24:17 52716 8192000000 10252819936 10709000192 1340399776 14.061 98 28 56 0 1356153808 9413712 6340320
16:26:10 42887 8192000000 10251245984 10718732288 1350461408 14.152 99 12 24 0 1363044512 9563616 3019488
16:27:56 72706 8192000000 10269628080 10726477824 1353018304 14.175 98 20 39 2 1368434960 10842848 4573808
16:30:08 79403 8192000000 10270700080 10725158912 1332109456 13.986 97 11 22 0 1344944016 10154560 2680000
16:32:30 57691 8192000000 10248268624 10703364096 1293585776 13.637 95 16 33 0 1310151440 12871600 3694064
16:34:28 38432 8192000000 10262707024 10712588288 1307055424 13.759 96 26 52 0 1332742064 19384496 6302144
16:36:16 40339 8192000000 10266651680 10708660224 1333109872 13.995 98 20 40 1 1349716768 12421488 4185408
16:37:57 24142 8192000000 9220340400 9489051648 376594128 4.395 28 14 28 0 388352528 8774720 2983680

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-04-2022 16:39:25 to 22-04-2022 16:39:40.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-04-2022 16:39:41 to 22-04-2022 16:39:43.

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          248599
MON$OLDEST_ACTIVE               248600
MON$OLDEST_SNAPSHOT             248600
MON$NEXT_TRANSACTION            248600
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                       2059104
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.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1452: 
:1453: mscs-test-161.budzdorov.ru    Fri Apr 22 16:38:26 2022
:1454:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1455: 
:1456: 
:1457: mscs-test-161.budzdorov.ru    Fri Apr 22 16:38:28 2022
:1458:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1459: 
:1460: 
:1461: mscs-test-161.budzdorov.ru    Fri Apr 22 16:38:28 2022
:1462:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1463: 
--- end of diff output ---

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

22.04.22 16:39:44. 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-22 16:39:46 No severe PSQL-related problems occured
22.04.22 16:39:48. End of report.