Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.12.2022 19:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15235888 kB
MemAvailable 30108636 kB
Buffers 0 kB
Cached 16501188 kB
SwapCached 3376 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-12-14 19:38:32
DTS_END                         2022-12-14 19:38:32.1860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(676:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53160
TRN_ID                          235558
ATT_ID                          668
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: 9000.74 24773 1080089
customer order: creation 165.05 368 19806
customer order: refuse 14.60 361 1752
order to supplier: creation 43.84 1247 5261
order to supplier: removal 3.36 106830 403
invoice (draft): creation 44.48 3152 5338
invoice (draft): removal 51.53 914 6183
invoice accept: apply 40.33 18530 4840
invoice accept: cancel 46.36 803 5563
customer reserve: creation 3767.48 183 452098
customer reserve: removal 4530.82 61 543698
realization accept: apply 80.47 188 9656
realization accept: cancel 85.15 113 10218
payment from customer: creation 59.21 119 7105
payment from customer: removal 5.88 97 706
payment to supplier: creation 47.52 124 5702
payment to supplier: removal 3.42 65 410
service: total inventory turnovers 4.47 1089 536
service: total monetary turnovers 6.07 52 728
service: aggregate perf. data 0.68 30534 82
service: refresh index statistics 0.03 330638 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-12-2022 19:40:38 to 14-12-2022 19:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3619
TEST_TIME 2 3671
TEST_TIME 3 3736
TEST_TIME 4 4973
TEST_TIME 5 5387
TEST_TIME 6 5662
TEST_TIME 7 5798
TEST_TIME 8 5669
TEST_TIME 9 5517
TEST_TIME 10 5757
TEST_TIME 11 5796
TEST_TIME 12 5819
TEST_TIME 13 5728
TEST_TIME 14 6267
TEST_TIME 15 6395
TEST_TIME 16 6775
TEST_TIME 17 6913
TEST_TIME 18 7020
TEST_TIME 19 6907
TEST_TIME 20 7252
TEST_TIME 21 7308
TEST_TIME 22 7353
TEST_TIME 23 7395
TEST_TIME 24 8058
TEST_TIME 25 8238
TEST_TIME 26 8995
TEST_TIME 27 10389
TEST_TIME 28 10544
TEST_TIME 29 10283
TEST_TIME 30 10128
TEST_TIME 31 10195
TEST_TIME 32 10222
TEST_TIME 33 10227
TEST_TIME 34 10108
TEST_TIME 35 9923
TEST_TIME 36 9797
TEST_TIME 37 9726
TEST_TIME 38 9624
TEST_TIME 39 9698
TEST_TIME 40 9561
TEST_TIME 41 9656
TEST_TIME 42 9565
TEST_TIME 43 9433
TEST_TIME 44 9365
TEST_TIME 45 9336
TEST_TIME 46 9393
TEST_TIME 47 9400
TEST_TIME 48 9279
TEST_TIME 49 9223
TEST_TIME 50 9217
TEST_TIME 51 9241
TEST_TIME 52 9235
TEST_TIME 53 9208
TEST_TIME 54 9187
TEST_TIME 55 9192
TEST_TIME 56 9220
TEST_TIME 57 9153
TEST_TIME 58 9261
TEST_TIME 59 9281
TEST_TIME 60 9311
TEST_TIME 61 9387
TEST_TIME 62 9313
TEST_TIME 63 9337
TEST_TIME 64 9420
TEST_TIME 65 9350
TEST_TIME 66 9447
TEST_TIME 67 9339
TEST_TIME 68 9279
TEST_TIME 69 9270
TEST_TIME 70 9263
TEST_TIME 71 9251
TEST_TIME 72 9243
TEST_TIME 73 9173
TEST_TIME 74 9176
TEST_TIME 75 9177
TEST_TIME 76 9150
TEST_TIME 77 9147
TEST_TIME 78 9116
TEST_TIME 79 9119
TEST_TIME 80 9172
TEST_TIME 81 9129
TEST_TIME 82 9186
TEST_TIME 83 9192
TEST_TIME 84 9200
TEST_TIME 85 9215
TEST_TIME 86 9244
TEST_TIME 87 9195
TEST_TIME 88 9301
TEST_TIME 89 9273
TEST_TIME 90 9243
TEST_TIME 91 9156
TEST_TIME 92 9174
TEST_TIME 93 9126
TEST_TIME 94 9127
TEST_TIME 95 9073
TEST_TIME 96 9095
TEST_TIME 97 9045
TEST_TIME 98 9076
TEST_TIME 99 9172
TEST_TIME 100 9158
TEST_TIME 101 9175
TEST_TIME 102 9176
TEST_TIME 103 9228
TEST_TIME 104 9190
TEST_TIME 105 9204
TEST_TIME 106 9220
TEST_TIME 107 9205
TEST_TIME 108 9219
TEST_TIME 109 9255
TEST_TIME 110 9268
TEST_TIME 111 9259
TEST_TIME 112 9178
TEST_TIME 113 9213
TEST_TIME 114 9194
TEST_TIME 115 9173
TEST_TIME 116 9153
TEST_TIME 117 9111
TEST_TIME 118 9102
TEST_TIME 119 9062
TEST_TIME 120 9004

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-12-2022 19:40:41 to 14-12-2022 19:40:45.

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 1499174 1499174 0 0.00 0 20429 6 0 0
doc_list_biud 1499172 1499172 0 0.00 0 31982 29 0 0
sp_add_invoice_to_stock 4985 4840 145 2.90 28 193118 18531 0 145
sp_cancel_adding_invoice 5563 5563 0 0.00 4 46572 804 0 0
sp_cancel_client_order 1752 1752 0 0.00 5 22562 362 0 0
sp_cancel_customer_reserve 543698 543698 0 0.00 0 21249 62 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 8865 98 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 2 3554 65 0 0
sp_cancel_supplier_invoice 6192 6183 9 0.14 1 26451 914 0 9
sp_cancel_supplier_order 403 403 0 0.00 8 1069288 106830 0 0
sp_cancel_write_off 10218 10218 0 0.00 1 11341 113 0 0
sp_client_order 19806 19806 0 0.00 3 25014 368 0 0
sp_customer_reserve 452098 452098 0 0.00 0 38985 183 0 0
sp_fill_shopping_cart 30401 30401 0 0.00 1 17369 39 0 0
sp_fill_shopping_cart_clo_res 452085 452085 0 0.00 0 13783 14 0 0
sp_get_clo_for_invoice 4826 4826 0 0.00 0 27964 330 0 0
sp_kill_cost_storno 16897 16897 0 0.00 0 9701 53 0 0
sp_kill_qstorno_ret_qs2qd 1104200 1104200 0 0.00 0 26436 34 0 0
sp_kill_qty_storno 582299 582299 0 0.00 0 31981 73 0 0
sp_lock_selected_doc 20149 20149 0 0.00 0 9802 8 0 0
sp_make_cost_storno 27289 27289 0 0.00 0 13412 53 0 0
sp_multiply_rows_for_pdistr 27289 27289 0 0.00 0 11005 29 0 0
sp_multiply_rows_for_qdistr 19806 19806 0 0.00 0 20132 154 0 0
sp_pay_from_customer 7105 7105 0 0.00 4 18860 120 0 0
sp_pay_to_supplier 5702 5702 0 0.00 4 13423 125 0 0
sp_payment_common 12807 12807 0 0.00 1 18825 33 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 22476 328 0 0
sp_qd_handle_on_invoice_upd_sts 10389 10389 0 0.00 0 31980 324 0 0
sp_qd_handle_on_reserve_upd_sts 9656 9656 0 0.00 0 24995 63 0 0
sp_reserve_write_off 9656 9656 0 0.00 6 25018 188 0 0
sp_supplier_invoice 5338 5338 0 0.00 5 73329 3152 0 0
sp_supplier_order 5261 5261 0 0.00 4 30074 1247 0 0
srv_aggregate_perf_data 82 82 0 0.00 2892 524608 30534 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2256 125571 44250 0 0
srv_find_qd_qs_mism 1033782 1033782 0 0.00 0 20421 6 0 0
srv_make_invnt_saldo 536 536 0 0.00 43 30709 1090 0 0
srv_make_money_saldo 728 728 0 0.00 1 22971 53 0 0
srv_recalc_idx_stat 4 4 0 0.00 156466 555819 330638 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4840 4840 0 0.00 0 31531 171 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 9154 109 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 11605 18 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 5749 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 3378 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 2539 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 374 374 0 0.00 0 1807 18 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 24847 1499 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 5984 10 0 0
t$perf-norm:sp_client_order 19806 19806 0 0.00 0 20316 17 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 2501 6 0 0
t$perf-norm:sp_pay_from_customer 7105 7105 0 0.00 0 9657 17 0 0
t$perf-norm:sp_pay_to_supplier 5702 5702 0 0.00 0 20319 14 0 0
t$perf-norm:sp_reserve_write_off 9656 9656 0 0.00 0 7919 14 0 0
t$perf-norm:sp_supplier_invoice 5338 5338 0 0.00 0 18934 18 0 0
t$perf-norm:sp_supplier_order 5261 5261 0 0.00 0 8858 16 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 399 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 67 2 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 713 13 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 4646 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9942 9652 290 2.91 0 9056 19 0 290
v_all_customers 36624 36624 0 0.00 0 41 0 0 0
v_all_suppliers 29716 29716 0 0.00 0 54 1 0 0
v_all_wares 39610 39610 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 5542 14 0 0
v_cancel_client_order 3504 3504 0 0.00 0 9578 8 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 1936 4 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 1747 8 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1443 6 0 0
v_cancel_supplier_invoice 766 748 18 2.34 0 9573 25 0 18
v_cancel_supplier_order 806 806 0 0.00 0 4908 14 0 0
v_cancel_write_off 1960 1960 0 0.00 0 9218 22 0 0
v_max_id_clo_ord 5259 5259 0 0.00 0 76 1 0 0
v_max_id_clo_res 973 973 0 0.00 0 2191 17 0 0
v_max_id_ord_sup 5336 5336 0 0.00 0 5205 7 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 5304 6 0 0
v_max_non_paid_realizn 3669 3669 0 0.00 0 6202 5 0 0
v_min_id_clo_ord 5258 5258 0 0.00 0 2032 4 0 0
v_min_id_clo_res 973 973 0 0.00 0 6721 15 0 0
v_min_id_ord_sup 5336 5336 0 0.00 0 9263 7 0 0
v_min_non_paid_invoice 5702 5702 0 0.00 0 12357 25 0 0
v_min_non_paid_realizn 7105 7105 0 0.00 0 12421 24 0 0
v_our_firm 9858 9858 0 0.00 0 54 1 0 0
v_reserve_write_off 19309 19309 0 0.00 0 10563 9 0 0
x_lock_depdocs_on_canc_invoice 11372 11372 0 0.00 0 20769 116 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 10119 371 0 0
x_make_qstorno_1000_1200 5261 5261 0 0.00 1 30064 1180 0 0
x_make_qstorno_1200_2000 5338 5338 0 0.00 2 72101 3001 0 0
x_make_qstorno_2100_3300 452098 452098 0 0.00 0 38985 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-12-2022 19:40:46 to 14-12-2022 19:40:50.

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
17:08:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 27237 8192000000 9895166208 10200207360 966389952 10.551 100 87 174 0 994754784 5442224 22922608
17:11:11 18893 8192000000 9941480464 10260561920 1016367328 11.037 100 90 180 0 1047528336 7586496 23574512
17:12:38 10597 8192000000 9984540720 10313003008 1061661440 11.472 100 85 170 0 1091950416 9645680 20643296
17:13:55 9248 8192000000 10038415824 10382839808 1120351280 12.030 100 85 170 0 1153249392 12265504 20632608
17:15:13 6185 8192000000 10081495824 10438680576 1166744512 12.466 100 85 168 0 1199011856 12975680 19291664
17:16:21 4567 8192000000 10123861376 10489675776 1209122512 12.861 100 75 150 1 1238653232 12700640 16830080
17:17:31 2079 8192000000 10156266128 10530086912 1242151344 13.166 100 85 168 0 1275353808 14884912 18317552
17:18:36 1541 8192000000 10179441168 10555039744 1266748800 13.392 100 88 176 1 1300019888 13410640 19860448
17:19:40 862 8192000000 10204026928 10586148864 1292592464 13.628 100 87 172 1 1324082336 13002608 18487264
17:20:42 2931 8192000000 10204565936 10599325696 1305098448 13.742 100 51 100 0 1323561152 7088768 11373936
17:21:47 2966 8192000000 10205113008 10614677504 1315689872 13.838 100 37 76 0 1335516032 11498880 8327280
17:22:51 46221 8192000000 10202412960 10624991232 1322768224 13.902 100 42 83 1 1338253632 5224432 10260976
17:24:44 5003 8192000000 10215181088 10635128832 1328427952 13.953 100 25 51 0 1339939664 5240592 6271120
17:25:49 9363 8192000000 10228455424 10640822272 1333167680 13.996 100 23 47 2 1343506448 5413152 4925616
17:27:02 14601 8192000000 10222166592 10638082048 1329193072 13.960 100 31 61 1 1342735328 5528608 8013648
17:28:39 6639 8192000000 10231161504 10644795392 1332696176 13.992 100 26 53 1 1343381440 4955104 5730160
17:29:47 4025 8192000000 10199653888 10608254976 1270312704 13.424 97 31 62 1 1288228128 10828416 7087008
17:30:54 11275 8192000000 10143182720 10549960704 1208189376 12.852 95 23 46 0 1218598032 5170992 5237664
17:32:06 49965 8192000000 10151197744 10544898048 1234713008 13.098 98 35 68 1 1249007728 5650208 8644512
17:33:57 6866 8192000000 10158448480 10564452352 1269073584 13.413 100 24 48 1 1281264480 6943536 5247360
17:35:04 7840 8192000000 10175024976 10588569600 1288143008 13.587 100 20 38 1 1297954752 5620832 4190912
17:36:15 18694 8192000000 10194116800 10605363200 1298826880 13.685 100 17 34 0 1308321616 5776704 3718032
17:37:36 22764 8192000000 10204361552 10619092992 1312042032 13.805 100 30 60 0 1324909040 5441424 7425584
17:39:05 16899 8192000000 10239728944 10654060544 1340702128 14.064 100 37 74 0 1356121184 7190288 8228768
17:40:48 23488 8192000000 10265133296 10683215872 1359123504 14.229 100 43 86 0 1381746816 12339360 10283952
17:42:21 32518 8192000000 10283236736 10708332544 1371830448 14.343 100 39 76 0 1393287728 12604416 8852864
17:43:58 14752 8192000000 10299041680 10728333312 1389923952 14.505 100 30 60 0 1411973104 14496848 7552304
17:45:24 6420 8192000000 10327324704 10754609152 1404540464 14.635 100 50 100 2 1437482976 21514208 11428304
17:46:41 4821 8192000000 10327519232 10757484544 1399572416 14.591 99 54 108 0 1431398016 18733344 13092256
17:47:57 4133 8192000000 10341938496 10768150528 1414537968 14.724 100 54 107 0 1450097600 22610416 12949216
17:49:07 5711 8192000000 10365015472 10794930176 1433572656 14.893 100 55 110 0 1476588256 30003984 13011616
17:50:23 2254 8192000000 10379669312 10801037312 1434167504 14.898 100 84 163 2 1489908592 35127504 20613584
17:51:27 5735 8192000000 10398181200 10821984256 1444582976 14.990 100 66 130 1 1503529808 42004240 16942592
17:52:39 7657 8192000000 10401433920 10838970368 1450691024 15.044 100 48 93 0 1510601024 47295344 12614656
17:53:51 3167 8192000000 10402087872 10832117760 1447628128 15.017 100 62 120 1 1511044240 48307312 15108800
17:55:00 2509 8192000000 10393566704 10820673536 1438727264 14.938 100 61 121 0 1500939600 46604944 15607392
17:56:07 3069 8192000000 10383002096 10808938496 1423612288 14.805 99 55 109 0 1482756800 45450304 13694208
17:57:15 33261 8192000000 10350269440 10768019456 1356312832 14.204 96 38 76 0 1416855296 50312912 10229552
17:58:53 8113 8192000000 10357158016 10781659136 1401878064 14.612 100 45 90 0 1464363232 50594816 11890352
18:00:37 17599 8192000000 10350556736 10770878464 1387924192 14.487 100 41 81 1 1455321424 56065104 11332128
18:02:06 6151 8192000000 10344925376 10778972160 1375420976 14.376 100 32 64 2 1451182944 66302704 9459264
18:03:21 23235 8192000000 10302231392 10723246080 1300124816 13.696 96 28 54 0 1380210448 70867760 9217872
18:05:07 29519 8192000000 10268541712 10717024256 1301554560 13.709 97 15 30 0 1356182192 50225056 4402576
18:06:40 53095 8192000000 10266734320 10691436544 1330676928 13.973 99 39 74 0 1349132256 9881616 8573712
18:08:39 13192 8192000000 10295858912 10722918400 1369267040 14.320 100 61 119 1 1399361696 17026896 13067760
18:09:53 62082 8192000000 10276663888 10736705536 1369471296 14.322 100 13 26 0 1396159600 23951040 2737264
18:13:01 63058 8192000000 10262109696 10701721600 1340006736 14.057 98 22 44 0 1356267632 11324592 4936304
18:15:08 86167 8192000000 10274214176 10728255488 1369930256 14.326 100 18 36 0 1387570448 13389840 4250352
18:17:46 76213 8192000000 10277389232 10724360192 1352495216 14.170 98 21 42 0 1366119360 9277392 4346752
18:20:06 73958 8192000000 10277744656 10728652800 1356372304 14.205 98 42 83 0 1372645152 6814496 9458352
18:22:21 4331 8192000000 10290538688 10729361408 1381655584 14.431 100 41 82 2 1400465888 8357920 10452384
18:23:26 26655 8192000000 10295072432 10744811520 1380312912 14.419 99 23 46 0 1398927920 13256640 5358368
18:25:01 54808 8192000000 10292683488 10749005824 1390757488 14.513 100 12 24 0 1407534096 13730768 3045840
18:28:35 55064 8192000000 10301767792 10757001216 1393049104 14.533 100 8 16 1 1411241824 16511696 1681024
18:30:35 54352 8192000000 10297838656 10754990080 1400326816 14.598 100 14 26 0 1411780752 8871088 2582848
18:32:31 23029 8192000000 10322541008 10764713984 1395171024 14.552 99 36 65 3 1409142576 6941984 7029568
18:33:56 58610 8192000000 10297784160 10750029824 1365459712 14.286 97 11 20 1 1377868672 10053216 2355744
18:36:13 120418 8192000000 10303324944 10751291392 1378983968 14.407 98 16 32 0 1396253248 14273696 2995584
18:39:21 64341 8192000000 10259885248 10703282176 1260830480 13.338 90 9 16 1 1268471904 6177856 1463568
18:41:27 23372 8192000000 10288755264 10717626368 1336799792 14.029 98 50 99 1 1363137696 15880816 10457088
18:43:31 12314 8192000000 10301462320 10735792128 1373003488 14.354 100 40 80 0 1394495520 12948848 8543184
18:44:47 97679 8192000000 10291929600 10728108032 1338859920 14.047 97 24 48 0 1357075696 12605072 5610704
18:47:28 93327 8192000000 10228838032 10670084096 1267626144 13.400 92 24 48 0 1288212880 14656288 5930448
18:50:11 52592 8192000000 10265705088 10715033600 1354273840 14.186 99 19 38 0 1372629120 13632480 4722800
18:52:55 70569 8192000000 10271677744 10727260160 1359815264 14.236 99 22 45 0 1377648864 12623264 5210336
18:55:43 46166 8192000000 10273066320 10722947072 1343875520 14.092 98 18 36 1 1358562864 10458144 4229200
18:57:35 81352 8192000000 10256485696 10708799488 1326501936 13.936 97 15 29 0 1340969152 11610064 2857152
18:59:58 58594 8192000000 10264316320 10714423296 1312775344 13.811 96 20 40 1 1332225408 14786272 4663792
19:02:01 40124 8192000000 10267586016 10704609280 1329939680 13.967 98 14 28 1 1344800672 11694656 3166336
19:03:47 81815 8192000000 10279576816 10725519360 1350435936 14.151 99 25 48 0 1370076464 14743888 4896640
19:06:19 58888 8192000000 10273614528 10732290048 1357029376 14.211 99 19 37 0 1376791488 15722160 4039952
19:08:25 125570 8192000000 10285106160 10733756416 1350909136 14.156 98 27 54 0 1368463248 11591968 5962144
19:11:32 11619 8192000000 10280490160 10727911424 1377166448 14.391 100 11 22 0 1387294064 7820880 2306736
19:12:44 88246 8192000000 10273361008 10729627648 1360486640 14.242 98 10 20 0 1371277088 8473616 2316832
19:15:27 63083 8192000000 10302110992 10757959680 1374775104 14.370 99 18 36 0 1399163776 20049328 4339344
19:17:39 77057 8192000000 10298755056 10754174976 1383738368 14.450 99 20 40 0 1400309408 12096192 4474848
19:20:33 44390 8192000000 10296729472 10744897536 1370936368 14.335 98 44 88 0 1390977952 10222192 9819392
19:22:18 33103 8192000000 10297750080 10732167168 1359321792 14.231 98 33 58 1 1372017680 6667152 6028736
19:23:53 72372 8192000000 10301983872 10759286784 1368404400 14.313 98 23 46 0 1388272256 15070624 4797232
19:26:07 23651 8192000000 10288672640 10736689152 1351254720 14.159 97 31 62 1 1368679936 10597808 6827408
19:27:31 20004 8192000000 10287954832 10734907392 1359517392 14.233 98 28 54 0 1381120080 15097040 6505648
19:28:51 116704 8192000000 10283226656 10734178304 1317383232 13.853 95 10 20 0 1335876416 16600800 1892384
19:32:01 76237 8192000000 10264288496 10728615936 1325239360 13.924 96 8 16 0 1339003712 12449744 1314608
19:34:18 67924 8192000000 10269262736 10727731200 1364332672 14.276 99 9 18 1 1373323168 6877824 2112672
19:36:26 66210 8192000000 10260463376 10718707712 1345264336 14.105 98 7 14 0 1353558224 6647472 1646416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-12-2022 19:40:52 to 14-12-2022 19:41:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-12-2022 19:41:08 to 14-12-2022 19:41:10.

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          234722
MON$OLDEST_ACTIVE               235702
MON$OLDEST_SNAPSHOT             235702
MON$NEXT_TRANSACTION            235702
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                       2007040
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Wed Dec 14 19:38:48 2022
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Wed Dec 14 19:38:54 2022
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
--- end of diff output ---

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

14.12.22 19:41:11. 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-12-14 19:41:14 No severe PSQL-related problems occured
14.12.22 19:41:15. End of report.