Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.10.2022 01:39
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 15563940 kB
MemAvailable 30448792 kB
Buffers 0 kB
Cached 16138848 kB
SwapCached 1952 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-10-22 01:38:31
DTS_END                         2022-10-22 01:38:31.7940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41448
TRN_ID                          239060
ATT_ID                          690
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: 8971.05 21170 1076526
customer order: creation 172.74 344 20729
customer order: refuse 14.29 322 1715
order to supplier: creation 44.93 1188 5391
order to supplier: removal 3.30 96145 396
invoice (draft): creation 47.08 3017 5650
invoice (draft): removal 49.25 852 5910
invoice accept: apply 41.80 18580 5016
invoice accept: cancel 44.58 818 5349
customer reserve: creation 3891.09 183 466931
customer reserve: removal 4366.41 56 523969
realization accept: apply 83.17 199 9980
realization accept: cancel 82.84 116 9941
payment from customer: creation 59.48 132 7137
payment from customer: removal 5.66 74 679
payment to supplier: creation 49.14 153 5897
payment to supplier: removal 3.38 95 406
service: total inventory turnovers 4.58 1060 549
service: total monetary turnovers 6.38 25 766
service: aggregate perf. data 0.93 17077 111
service: refresh index statistics 0.03 282962 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-10-2022 01:39:53 to 22-10-2022 01:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4914
TEST_TIME 2 4038
TEST_TIME 3 3864
TEST_TIME 4 3674
TEST_TIME 5 3789
TEST_TIME 6 3780
TEST_TIME 7 4056
TEST_TIME 8 4931
TEST_TIME 9 5375
TEST_TIME 10 5861
TEST_TIME 11 5965
TEST_TIME 12 6200
TEST_TIME 13 6287
TEST_TIME 14 6887
TEST_TIME 15 7285
TEST_TIME 16 7446
TEST_TIME 17 7318
TEST_TIME 18 7443
TEST_TIME 19 7693
TEST_TIME 20 7936
TEST_TIME 21 8325
TEST_TIME 22 8589
TEST_TIME 23 8408
TEST_TIME 24 8667
TEST_TIME 25 8555
TEST_TIME 26 8856
TEST_TIME 27 9026
TEST_TIME 28 10548
TEST_TIME 29 10729
TEST_TIME 30 10455
TEST_TIME 31 10283
TEST_TIME 32 10083
TEST_TIME 33 10106
TEST_TIME 34 10001
TEST_TIME 35 9848
TEST_TIME 36 9701
TEST_TIME 37 9565
TEST_TIME 38 9370
TEST_TIME 39 9590
TEST_TIME 40 9733
TEST_TIME 41 9637
TEST_TIME 42 9489
TEST_TIME 43 9388
TEST_TIME 44 9379
TEST_TIME 45 9290
TEST_TIME 46 9273
TEST_TIME 47 9293
TEST_TIME 48 9152
TEST_TIME 49 9202
TEST_TIME 50 9140
TEST_TIME 51 9145
TEST_TIME 52 9246
TEST_TIME 53 9222
TEST_TIME 54 9173
TEST_TIME 55 9197
TEST_TIME 56 9339
TEST_TIME 57 9354
TEST_TIME 58 9345
TEST_TIME 59 9347
TEST_TIME 60 9428
TEST_TIME 61 9487
TEST_TIME 62 9570
TEST_TIME 63 9557
TEST_TIME 64 9466
TEST_TIME 65 9356
TEST_TIME 66 9329
TEST_TIME 67 9304
TEST_TIME 68 9233
TEST_TIME 69 9314
TEST_TIME 70 9271
TEST_TIME 71 9206
TEST_TIME 72 9182
TEST_TIME 73 9162
TEST_TIME 74 9129
TEST_TIME 75 9112
TEST_TIME 76 9222
TEST_TIME 77 9224
TEST_TIME 78 9256
TEST_TIME 79 9233
TEST_TIME 80 9212
TEST_TIME 81 9228
TEST_TIME 82 9258
TEST_TIME 83 9249
TEST_TIME 84 9332
TEST_TIME 85 9281
TEST_TIME 86 9199
TEST_TIME 87 9174
TEST_TIME 88 9121
TEST_TIME 89 9156
TEST_TIME 90 9125
TEST_TIME 91 9085
TEST_TIME 92 9067
TEST_TIME 93 9083
TEST_TIME 94 9059
TEST_TIME 95 9062
TEST_TIME 96 9158
TEST_TIME 97 9143
TEST_TIME 98 9138
TEST_TIME 99 9191
TEST_TIME 100 9204
TEST_TIME 101 9195
TEST_TIME 102 9197
TEST_TIME 103 9198
TEST_TIME 104 9230
TEST_TIME 105 9206
TEST_TIME 106 9246
TEST_TIME 107 9174
TEST_TIME 108 9141
TEST_TIME 109 9095
TEST_TIME 110 9105
TEST_TIME 111 9068
TEST_TIME 112 9025
TEST_TIME 113 8982
TEST_TIME 114 8971
TEST_TIME 115 8972
TEST_TIME 116 8993
TEST_TIME 117 8973
TEST_TIME 118 8967
TEST_TIME 119 9031
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-10-2022 01:39:57 to 22-10-2022 01:40:00.

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 1506923 1506923 0 0.00 0 17786 7 0 0
doc_list_biud 1506894 1506894 0 0.00 0 61302 25 0 0
sp_add_invoice_to_stock 5126 5016 110 2.14 34 226335 18581 0 110
sp_cancel_adding_invoice 5349 5349 0 0.00 4 64293 819 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 8698 323 0 0
sp_cancel_customer_reserve 523969 523969 0 0.00 0 61309 57 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 2 7979 75 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 11955 96 0 0
sp_cancel_supplier_invoice 5918 5910 8 0.13 2 26639 852 0 8
sp_cancel_supplier_order 396 396 0 0.00 5 971958 96145 0 0
sp_cancel_write_off 9941 9941 0 0.00 1 13437 116 0 0
sp_client_order 20729 20729 0 0.00 3 23644 345 0 0
sp_customer_reserve 466931 466931 0 0.00 0 60433 184 0 0
sp_fill_shopping_cart 31757 31757 0 0.00 1 26520 44 0 0
sp_fill_shopping_cart_clo_res 466925 466925 0 0.00 0 21829 16 0 0
sp_get_clo_for_invoice 5010 5010 0 0.00 0 32656 347 0 0
sp_kill_cost_storno 16375 16375 0 0.00 0 11946 57 0 0
sp_kill_qstorno_ret_qs2qd 1064185 1064185 0 0.00 0 61277 31 0 0
sp_kill_qty_storno 562260 562260 0 0.00 0 61301 68 0 0
sp_lock_selected_doc 20695 20695 0 0.00 0 9886 4 0 0
sp_make_cost_storno 28024 28024 0 0.00 0 11678 62 0 0
sp_multiply_rows_for_pdistr 28019 28019 0 0.00 0 11674 32 0 0
sp_multiply_rows_for_qdistr 20729 20729 0 0.00 0 23613 157 0 0
sp_pay_from_customer 7137 7137 0 0.00 4 13002 132 0 0
sp_pay_to_supplier 5897 5897 0 0.00 4 17225 154 0 0
sp_payment_common 13034 13034 0 0.00 1 17160 35 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 8690 286 0 0
sp_qd_handle_on_invoice_upd_sts 10359 10359 0 0.00 0 29122 310 0 0
sp_qd_handle_on_reserve_upd_sts 9970 9970 0 0.00 0 14895 65 0 0
sp_reserve_write_off 9980 9980 0 0.00 6 14917 199 0 0
sp_supplier_invoice 5650 5650 0 0.00 6 77385 3018 0 0
sp_supplier_order 5391 5391 0 0.00 5 35422 1189 0 0
srv_aggregate_perf_data 111 111 0 0.00 2706 286052 17077 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1545 125873 45153 0 0
srv_find_qd_qs_mism 1027527 1027527 0 0.00 0 17785 6 0 0
srv_make_invnt_saldo 549 549 0 0.00 24 34004 1060 0 0
srv_make_money_saldo 766 766 0 0.00 1 1945 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 180556 367030 282963 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5016 5016 0 0.00 0 26781 172 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 5196 97 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 3301 15 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 3547 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 7105 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 3633 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 372 372 0 0.00 0 1473 25 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 21928 1482 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 4891 18 0 0
t$perf-norm:sp_client_order 20729 20729 0 0.00 0 24826 16 0 0
t$perf-norm:sp_customer_reserve 947 947 0 0.00 0 2908 10 0 0
t$perf-norm:sp_pay_from_customer 7137 7137 0 0.00 0 18119 11 0 0
t$perf-norm:sp_pay_to_supplier 5897 5897 0 0.00 0 5951 8 0 0
t$perf-norm:sp_reserve_write_off 9980 9980 0 0.00 0 5032 10 0 0
t$perf-norm:sp_supplier_invoice 5650 5650 0 0.00 0 14380 18 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 4869 15 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 340 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 21 1 0 0
t$perf-norm:srv_make_invnt_saldo 549 549 0 0.00 0 3008 25 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 2678 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 140 42 0 0
v_add_invoice_to_stock 10235 10015 220 2.14 0 9043 22 0 220
v_all_customers 37866 37866 0 0.00 0 92 0 0 0
v_all_suppliers 30418 30418 0 0.00 0 178 1 0 0
v_all_wares 41444 41444 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1662 8 0 0
v_cancel_client_order 3430 3430 0 0.00 0 6524 13 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 7902 15 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1843 7 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1552 7 0 0
v_cancel_supplier_invoice 760 744 16 2.10 0 5418 26 0 16
v_cancel_supplier_order 792 792 0 0.00 0 6369 13 0 0
v_cancel_write_off 2070 2070 0 0.00 0 9537 16 0 0
v_max_id_clo_ord 5389 5389 0 0.00 0 517 1 0 0
v_max_id_clo_res 947 947 0 0.00 0 1194 8 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 4689 5 0 0
v_max_non_paid_invoice 1723 1723 0 0.00 0 2153 5 0 0
v_max_non_paid_realizn 3739 3739 0 0.00 0 6947 6 0 0
v_min_id_clo_ord 5389 5389 0 0.00 0 5902 8 0 0
v_min_id_clo_res 947 947 0 0.00 0 5158 18 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 4753 7 0 0
v_min_non_paid_invoice 5897 5897 0 0.00 0 17155 28 0 0
v_min_non_paid_realizn 7137 7137 0 0.00 0 9138 27 0 0
v_our_firm 10374 10374 0 0.00 0 19 1 0 0
v_reserve_write_off 19940 19940 0 0.00 0 8957 11 0 0
x_lock_depdocs_on_canc_invoice 10887 10887 0 0.00 0 15432 130 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 10634 408 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 1 35416 1115 0 0
x_make_qstorno_1200_2000 5650 5650 0 0.00 3 77338 2845 0 0
x_make_qstorno_2100_3300 466931 466931 0 0.00 0 60431 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-10-2022 01:40:01 to 22-10-2022 01:40:05.

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:35 5 8192000000 8804607664 8818102272 10000480 0.121 4 2 3 0 10144240 68800 74960
23:09:36 39726 8192000000 9908508144 10218065920 987146688 10.754 100 91 182 0 1017056736 6019392 23890656
23:11:22 20546 8192000000 9950372448 10275188736 1035127312 11.218 100 89 178 0 1065658704 7657648 22873744
23:12:47 14367 8192000000 9988727728 10325856256 1079001824 11.638 100 84 168 0 1108930272 8784864 21143584
23:14:10 7812 8192000000 10026112480 10376892416 1120330864 12.030 100 74 148 0 1147281200 9815776 17134560
23:15:24 8573 8192000000 10079630736 10438119424 1168238240 12.480 100 77 154 0 1198348800 11881568 18228992
23:16:35 3375 8192000000 10109560608 10478989312 1203661488 12.810 100 79 159 0 1235176848 13825488 17689872
23:17:41 2305 8192000000 10152505392 10524737536 1236416688 13.113 100 82 164 0 1268920720 14798592 17705440
23:18:44 1037 8192000000 10184400416 10563289088 1271927744 13.439 100 85 167 1 1302653488 12749184 17976560
23:19:48 2126 8192000000 10208500992 10596712448 1295273488 13.652 100 76 152 0 1324422912 12277440 16871984
23:20:51 18075 8192000000 10194829024 10606329856 1303853952 13.730 100 25 50 0 1315882976 6212384 5816640
23:22:12 5734 8192000000 10206652016 10627268608 1323295872 13.907 100 23 45 0 1334764272 6473360 4995040
23:23:18 10669 8192000000 10210909712 10626035712 1327132944 13.941 100 16 34 0 1336396144 5306384 3956816
23:24:29 64619 8192000000 10245901568 10648367104 1334033504 14.004 100 55 111 0 1353108656 5288448 13786704
23:26:34 4364 8192000000 10232476672 10648125440 1337969120 14.039 100 31 63 0 1350437632 5803328 6665184
23:27:40 5441 8192000000 10229579392 10649088000 1338143280 14.041 100 22 41 2 1348809856 5834048 4832528
23:28:53 61732 8192000000 10196257072 10619531264 1307411088 13.763 99 29 58 0 1319344576 5239072 6694416
23:30:59 7525 8192000000 10150030352 10551349248 1205373680 12.826 94 15 30 0 1213375040 4541680 3459680
23:32:11 10260 8192000000 10141838832 10539474944 1211442128 12.882 96 26 52 0 1222988640 5326848 6219664
23:33:22 17353 8192000000 10152559568 10546679808 1228520032 13.040 97 46 95 0 1245134048 5578336 11035680
23:35:15 5961 8192000000 10172789632 10575732736 1278957696 13.503 100 26 52 1 1291259584 6800928 5500960
23:36:22 7532 8192000000 10175066320 10589446144 1290396992 13.608 100 20 38 0 1300509984 5257024 4855968
23:37:34 7068 8192000000 10188809088 10602188800 1301267328 13.707 100 23 46 0 1310896672 4863984 4765360
23:38:45 13708 8192000000 10224977088 10635382784 1327752768 13.947 100 25 50 0 1338638528 5111520 5774240
23:40:02 88154 8192000000 10246753872 10667724800 1345566864 14.108 100 32 64 0 1361472464 8747104 7158496
23:42:37 11289 8192000000 10269894464 10685861888 1358552448 14.224 100 47 92 0 1382929008 13773888 10602672
23:43:59 9576 8192000000 10285225424 10705063936 1372758896 14.352 100 49 95 1 1401028112 17224912 11044304
23:45:14 13919 8192000000 10296462848 10716090368 1374665760 14.369 100 48 92 3 1408274736 22310080 11298896
23:46:35 2852 8192000000 10307093024 10724974592 1383754720 14.450 100 49 94 0 1414752720 19014240 11983760
23:47:43 6978 8192000000 10327449808 10750685184 1404179264 14.632 100 52 103 0 1439926352 23407984 12339104
23:48:54 2769 8192000000 10346358608 10766786560 1412608464 14.707 100 65 128 0 1454094944 26074736 15411744
23:50:02 6556 8192000000 10346891712 10772705280 1411643920 14.699 100 59 115 0 1457741968 31333680 14764368
23:51:15 13885 8192000000 10358058528 10775633920 1414236112 14.722 100 72 142 3 1464866160 33798768 16831280
23:52:44 4087 8192000000 10332630784 10749411328 1389927424 14.505 100 57 114 1 1439269664 35419120 13923120
23:53:54 3767 8192000000 10313813472 10729918464 1369445424 14.322 99 53 104 0 1418937536 36218896 13273216
23:55:06 10998 8192000000 10327224624 10746818560 1379916944 14.416 100 45 90 0 1428938288 37737792 11283552
23:56:21 2734 8192000000 10359423312 10772504576 1400590096 14.600 100 79 156 0 1464976080 44273920 20112064
23:57:34 8465 8192000000 10297915520 10710228992 1336315616 14.024 97 46 92 0 1392887568 43711840 12860112
23:58:50 1545 8192000000 10344524352 10753617920 1381449856 14.430 100 74 145 0 1447543408 47549632 18543920
23:59:57 11363 8192000000 10307950336 10727313408 1348618896 14.135 98 43 86 0 1404496448 44725840 11151712
00:01:14 10638 8192000000 10300675008 10723635200 1340440912 14.061 98 31 59 0 1385498672 37230784 7826976
00:02:34 16464 8192000000 10279742624 10709372928 1319906560 13.876 97 24 48 0 1368546576 41527136 7112880
00:04:16 14052 8192000000 10287094096 10708512768 1327063728 13.941 100 29 56 0 1390125280 54601312 8460240
00:05:39 33505 8192000000 10274050784 10717159424 1285921520 13.567 96 28 56 0 1362044944 67359504 8763920
00:07:13 48341 8192000000 10248693328 10696605696 1329284512 13.961 99 10 22 0 1345304768 13478768 2541488
00:09:52 43227 8192000000 10275535872 10715369472 1362507056 14.260 100 23 45 0 1377026896 9953824 4566016
00:11:35 50886 8192000000 10277356448 10731663360 1367207952 14.302 100 13 27 0 1389344000 19168784 2967264
00:13:27 55401 8192000000 10278627552 10717130752 1322856368 13.903 96 14 28 0 1341157744 14973168 3328208
00:15:28 97420 8192000000 10284469408 10728022016 1368980752 14.318 99 23 47 0 1397318192 22318368 6019072
00:18:40 115789 8192000000 10285965360 10735230976 1378799264 14.406 100 29 57 0 1393295328 8046800 6449264
00:21:37 33526 8192000000 10310174688 10751909888 1396211184 14.561 100 48 93 0 1417771424 10935440 10624800
00:23:11 39523 8192000000 10291789712 10750758912 1395980832 14.559 100 15 29 0 1410392144 11240800 3170512
00:24:54 40096 8192000000 10286582864 10761105408 1394468608 14.546 100 4 8 0 1407428864 11973920 986336
00:26:37 64948 8192000000 10287814048 10757746688 1399935824 14.594 100 17 34 0 1414302320 10141904 4224592
00:28:44 42355 8192000000 10310691632 10781134848 1407148544 14.659 100 21 42 0 1425873344 13816624 4908176
00:30:28 45584 8192000000 10291096544 10754867200 1373102720 14.355 98 8 16 1 1390429216 15351024 1975472
00:32:34 22402 8192000000 10307931248 10756616192 1397015456 14.568 100 36 72 0 1415255408 10032704 8207248
00:34:01 36141 8192000000 10328190272 10789068800 1414196064 14.721 100 32 64 0 1438097120 16425984 7475072
00:35:44 55504 8192000000 10316911120 10775863296 1385855680 14.469 98 26 52 1 1403591760 12046160 5689920
00:37:43 55947 8192000000 10291482816 10755686400 1307250336 13.761 93 28 55 1 1330434592 16793568 6390688
00:40:01 78743 8192000000 10306145136 10746843136 1359741776 14.235 97 12 24 0 1378096176 15906240 2448160
00:42:23 80685 8192000000 10274895856 10724098048 1339199856 14.050 96 24 48 1 1353607104 9280160 5127088
00:44:47 62614 8192000000 10275014096 10734039040 1354201776 14.185 97 9 18 0 1371038880 14525280 2311824
00:47:17 114906 8192000000 10282901552 10751397888 1365432352 14.286 98 15 30 0 1387439376 18456400 3550624
00:50:12 37731 8192000000 10276961280 10726940672 1351018448 14.157 98 28 57 0 1365560032 8233776 6307808
00:51:50 59866 8192000000 10269402288 10719846400 1308685328 13.774 95 20 40 0 1327042784 14205040 4152416
00:54:02 91032 8192000000 10245325264 10692001792 1261093680 13.340 93 19 38 1 1283354656 17506896 4754080
00:56:35 63454 8192000000 10233526320 10670653440 1257154304 13.304 94 24 48 0 1272588224 10338704 5095216
00:58:39 32163 8192000000 10250267008 10682998784 1316877696 13.848 98 32 63 0 1335008288 11170320 6960272
01:00:20 72249 8192000000 10261715888 10702479360 1331329776 13.979 98 22 44 0 1353784224 17255248 5199200
01:02:35 111928 8192000000 10262412288 10707873792 1336595904 14.027 98 16 32 0 1354507424 14139136 3772384
01:05:43 59723 8192000000 10275621760 10725847040 1372858016 14.353 100 28 56 0 1391071712 11278560 6935136
01:07:43 34420 8192000000 10258282304 10730962944 1355884240 14.200 99 3 6 0 1368974256 12794080 295936
01:09:19 72306 8192000000 10257873168 10717356032 1336750848 14.028 97 10 20 0 1345234928 6691152 1792928
01:11:34 65742 8192000000 10284571296 10730831872 1354511552 14.188 98 24 47 0 1374945296 14985952 5447792
01:13:42 75363 8192000000 10279389552 10745827328 1367665408 14.306 99 8 16 0 1382082000 12663008 1753584
01:16:05 103629 8192000000 10289126544 10757496832 1345694928 14.109 97 11 22 1 1369561792 21255920 2610944
01:18:49 56486 8192000000 10286851440 10729963520 1318949696 13.867 95 21 42 0 1332256864 8878416 4428752
01:20:49 48304 8192000000 10300398288 10741530624 1362610624 14.261 98 26 52 0 1381236832 12991152 5635056
01:22:42 39684 8192000000 10300655552 10744659968 1364814096 14.281 98 25 48 0 1378895968 8951680 5130192
01:24:33 125872 8192000000 10285852720 10740830208 1374619088 14.368 99 27 54 0 1394085184 13782208 5683888
01:27:50 37896 8192000000 10244246672 10706411520 1317729488 13.856 96 26 52 0 1335935776 12556208 5650080
01:29:33 52371 8192000000 10262614864 10721902592 1370570192 14.332 100 10 20 0 1379221040 6643392 2007456
01:31:27 90892 8192000000 10278289536 10740801536 1347765552 14.127 97 22 43 0 1364596208 11986400 4844256
01:34:00 26838 8192000000 10272098352 10739228672 1378150384 14.400 100 19 38 0 1392536016 9579968 4805664
01:35:30 17335 8192000000 10272273488 10720653312 1357033344 14.211 99 27 54 0 1369464048 7100208 5330496
01:36:49 43058 8192000000 10265816416 10716278784 1342000208 14.075 98 24 48 0 1358673552 11199264 5474080

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-10-2022 01:40:07 to 22-10-2022 01:40:22.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-10-2022 01:40:23 to 22-10-2022 01:40:25.

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          238841
MON$OLDEST_ACTIVE               239790
MON$OLDEST_SNAPSHOT             239790
MON$NEXT_TRANSACTION            239790
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                       2014016
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Sat Oct 22 01:38:43 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Sat Oct 22 01:38:45 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
--- end of diff output ---

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

22.10.22 01:40:26. 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-10-22 01:40:29 No severe PSQL-related problems occured
22.10.22 01:40:30. End of report.