Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2022 19: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 15220144 kB
MemAvailable 30071636 kB
Buffers 0 kB
Cached 16474332 kB
SwapCached 3264 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-11 19:38:30
DTS_END                         2022-12-11 19:38:30.3330
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/35436
TRN_ID                          238271
ATT_ID                          674
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: 9108.29 20158 1092995
customer order: creation 166.14 395 19937
customer order: refuse 13.65 378 1638
order to supplier: creation 45.60 1345 5472
order to supplier: removal 3.46 105349 415
invoice (draft): creation 44.19 3265 5303
invoice (draft): removal 52.41 866 6289
invoice accept: apply 41.02 17265 4922
invoice accept: cancel 47.33 883 5679
customer reserve: creation 3744.93 173 449392
customer reserve: removal 4664.73 62 559768
realization accept: apply 80.94 191 9713
realization accept: cancel 77.77 112 9332
payment from customer: creation 57.47 107 6896
payment from customer: removal 5.46 104 655
payment to supplier: creation 48.44 133 5813
payment to supplier: removal 3.13 123 375
service: total inventory turnovers 4.39 1238 527
service: total monetary turnovers 6.42 23 770
service: aggregate perf. data 0.79 21297 95
service: refresh index statistics 0.03 249843 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2022 19:39:42 to 11-12-2022 19:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4209
TEST_TIME 2 3761
TEST_TIME 3 4032
TEST_TIME 4 4880
TEST_TIME 5 4715
TEST_TIME 6 4353
TEST_TIME 7 4708
TEST_TIME 8 5149
TEST_TIME 9 5296
TEST_TIME 10 5782
TEST_TIME 11 5910
TEST_TIME 12 5905
TEST_TIME 13 5833
TEST_TIME 14 6295
TEST_TIME 15 6366
TEST_TIME 16 6620
TEST_TIME 17 6977
TEST_TIME 18 6906
TEST_TIME 19 7000
TEST_TIME 20 7536
TEST_TIME 21 7815
TEST_TIME 22 8144
TEST_TIME 23 8789
TEST_TIME 24 9035
TEST_TIME 25 9125
TEST_TIME 26 10351
TEST_TIME 27 10513
TEST_TIME 28 10328
TEST_TIME 29 10293
TEST_TIME 30 10457
TEST_TIME 31 10400
TEST_TIME 32 10304
TEST_TIME 33 10183
TEST_TIME 34 10000
TEST_TIME 35 9829
TEST_TIME 36 9741
TEST_TIME 37 9642
TEST_TIME 38 9578
TEST_TIME 39 9859
TEST_TIME 40 9686
TEST_TIME 41 9590
TEST_TIME 42 9456
TEST_TIME 43 9470
TEST_TIME 44 9407
TEST_TIME 45 9616
TEST_TIME 46 9480
TEST_TIME 47 9383
TEST_TIME 48 9278
TEST_TIME 49 9194
TEST_TIME 50 9032
TEST_TIME 51 9070
TEST_TIME 52 9195
TEST_TIME 53 9078
TEST_TIME 54 9040
TEST_TIME 55 9028
TEST_TIME 56 9046
TEST_TIME 57 9003
TEST_TIME 58 8947
TEST_TIME 59 9050
TEST_TIME 60 9080
TEST_TIME 61 9056
TEST_TIME 62 9104
TEST_TIME 63 9131
TEST_TIME 64 9143
TEST_TIME 65 9093
TEST_TIME 66 9172
TEST_TIME 67 9217
TEST_TIME 68 9309
TEST_TIME 69 9351
TEST_TIME 70 9370
TEST_TIME 71 9351
TEST_TIME 72 9330
TEST_TIME 73 9433
TEST_TIME 74 9405
TEST_TIME 75 9352
TEST_TIME 76 9349
TEST_TIME 77 9315
TEST_TIME 78 9327
TEST_TIME 79 9288
TEST_TIME 80 9262
TEST_TIME 81 9253
TEST_TIME 82 9212
TEST_TIME 83 9167
TEST_TIME 84 9109
TEST_TIME 85 9117
TEST_TIME 86 9074
TEST_TIME 87 9074
TEST_TIME 88 9135
TEST_TIME 89 9122
TEST_TIME 90 9162
TEST_TIME 91 9183
TEST_TIME 92 9228
TEST_TIME 93 9216
TEST_TIME 94 9187
TEST_TIME 95 9298
TEST_TIME 96 9372
TEST_TIME 97 9369
TEST_TIME 98 9353
TEST_TIME 99 9369
TEST_TIME 100 9344
TEST_TIME 101 9387
TEST_TIME 102 9387
TEST_TIME 103 9304
TEST_TIME 104 9308
TEST_TIME 105 9275
TEST_TIME 106 9260
TEST_TIME 107 9294
TEST_TIME 108 9251
TEST_TIME 109 9221
TEST_TIME 110 9191
TEST_TIME 111 9142
TEST_TIME 112 9110
TEST_TIME 113 9078
TEST_TIME 114 9055
TEST_TIME 115 9093
TEST_TIME 116 9110
TEST_TIME 117 9119
TEST_TIME 118 9153
TEST_TIME 119 9148
TEST_TIME 120 9114

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-12-2022 19:39:45 to 11-12-2022 19:39:49.

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 1509993 1509993 0 0.00 0 22522 6 0 0
doc_list_biud 1509993 1509993 0 0.00 0 29677 29 0 0
sp_add_invoice_to_stock 5056 4922 134 2.65 26 166868 17266 0 134
sp_cancel_adding_invoice 5679 5679 0 0.00 5 70041 884 0 0
sp_cancel_client_order 1638 1638 0 0.00 3 11557 378 0 0
sp_cancel_customer_reserve 559768 559768 0 0.00 0 29682 63 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 12796 105 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 7620 123 0 0
sp_cancel_supplier_invoice 6303 6289 14 0.22 2 15747 867 0 14
sp_cancel_supplier_order 415 415 0 0.00 6 1048575 105350 0 0
sp_cancel_write_off 9332 9332 0 0.00 1 15465 112 0 0
sp_client_order 19937 19937 0 0.00 3 84942 396 0 0
sp_customer_reserve 449392 449392 0 0.00 0 37947 173 0 0
sp_fill_shopping_cart 30710 30710 0 0.00 1 20615 39 0 0
sp_fill_shopping_cart_clo_res 449382 449382 0 0.00 0 17383 15 0 0
sp_get_clo_for_invoice 4912 4912 0 0.00 0 29630 351 0 0
sp_kill_cost_storno 16041 16041 0 0.00 0 8371 54 0 0
sp_kill_qstorno_ret_qs2qd 1135572 1135572 0 0.00 0 29625 34 0 0
sp_kill_qty_storno 597746 597746 0 0.00 0 29677 74 0 0
sp_lock_selected_doc 20202 20202 0 0.00 0 9508 8 0 0
sp_make_cost_storno 27334 27334 0 0.00 0 22520 53 0 0
sp_multiply_rows_for_pdistr 27334 27334 0 0.00 0 5253 26 0 0
sp_multiply_rows_for_qdistr 19937 19937 0 0.00 0 79903 178 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 13931 107 0 0
sp_pay_to_supplier 5813 5813 0 0.00 5 22529 133 0 0
sp_payment_common 12709 12709 0 0.00 1 13926 32 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 11551 336 0 0
sp_qd_handle_on_invoice_upd_sts 10591 10591 0 0.00 0 29541 337 0 0
sp_qd_handle_on_reserve_upd_sts 9713 9713 0 0.00 0 15865 76 0 0
sp_reserve_write_off 9713 9713 0 0.00 5 15897 192 0 0
sp_supplier_invoice 5303 5303 0 0.00 7 89185 3265 0 0
sp_supplier_order 5472 5472 0 0.00 5 34896 1346 0 0
srv_aggregate_perf_data 95 95 0 0.00 2559 296636 21298 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1249 185325 41819 0 0
srv_find_qd_qs_mism 1047005 1047005 0 0.00 0 16608 5 0 0
srv_make_invnt_saldo 527 527 0 0.00 42 114198 1239 0 0
srv_make_money_saldo 770 770 0 0.00 0 792 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 158556 387184 249843 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4922 4922 0 0.00 0 25522 199 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 38254 222 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 8432 19 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 1022 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 6935 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 1009 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 8554 41 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 28423 1532 0 0
t$perf-norm:sp_cancel_write_off 1017 1017 0 0.00 0 8387 20 0 0
t$perf-norm:sp_client_order 19937 19937 0 0.00 0 37018 20 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 19174 42 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 6414 13 0 0
t$perf-norm:sp_pay_to_supplier 5813 5813 0 0.00 0 11508 12 0 0
t$perf-norm:sp_reserve_write_off 9713 9713 0 0.00 0 10930 18 0 0
t$perf-norm:sp_supplier_invoice 5303 5303 0 0.00 0 8382 24 0 0
t$perf-norm:sp_supplier_order 5472 5472 0 0.00 0 9481 18 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 275 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 595 14 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 2038 26 0 0
t$perf-norm:srv_make_money_saldo 770 770 0 0.00 0 2652 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10092 9824 268 2.65 0 6475 24 0 268
v_all_customers 36400 36400 0 0.00 0 15 0 0 0
v_all_suppliers 30186 30186 0 0.00 0 17 1 0 0
v_all_wares 39874 39874 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 5584 13 0 0
v_cancel_client_order 3276 3276 0 0.00 0 2383 7 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2137 6 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 2601 14 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 5707 13 0 0
v_cancel_supplier_invoice 844 816 28 3.31 0 387 14 0 28
v_cancel_supplier_order 830 830 0 0.00 0 3661 11 0 0
v_cancel_write_off 2034 2034 0 0.00 0 5631 17 0 0
v_max_id_clo_ord 5472 5472 0 0.00 0 5993 3 0 0
v_max_id_clo_res 997 997 0 0.00 0 2859 20 0 0
v_max_id_ord_sup 5301 5301 0 0.00 0 8152 7 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 1867 4 0 0
v_max_non_paid_realizn 3732 3732 0 0.00 0 6957 10 0 0
v_min_id_clo_ord 5472 5472 0 0.00 0 1425 4 0 0
v_min_id_clo_res 997 997 0 0.00 0 4496 9 0 0
v_min_id_ord_sup 5301 5301 0 0.00 0 7275 7 0 0
v_min_non_paid_invoice 5813 5813 0 0.00 0 4302 21 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 6246 21 0 0
v_our_firm 9802 9802 0 0.00 0 64 1 0 0
v_reserve_write_off 19426 19426 0 0.00 0 5583 8 0 0
x_lock_depdocs_on_canc_invoice 11560 11560 0 0.00 0 17514 117 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 11758 411 0 0
x_make_qstorno_1000_1200 5472 5472 0 0.00 1 34875 1273 0 0
x_make_qstorno_1200_2000 5303 5303 0 0.00 3 83246 3123 0 0
x_make_qstorno_2100_3300 449392 449392 0 0.00 0 37946 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2022 19:39:50 to 11-12-2022 19:39:54.

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:34 2 8192000000 8797443824 8808136704 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:36 28086 8192000000 9908755168 10222833664 991446128 10.796 100 85 170 0 1020151536 6082592 22622816
17:11:08 17548 8192000000 9953992576 10275373056 1033873488 11.206 100 92 184 1 1065696832 8257952 23565392
17:12:30 14364 8192000000 9994403296 10326732800 1074383792 11.594 100 86 172 0 1105116352 9194256 21538304
17:13:50 11907 8192000000 10053372112 10404319232 1139403568 12.210 100 83 166 1 1169789504 10799504 19586432
17:15:05 6329 8192000000 10096617648 10456322048 1181429696 12.604 100 80 159 0 1212404464 12954224 18020544
17:16:19 3590 8192000000 10132598224 10502995968 1216220544 12.927 100 83 167 3 1248386896 14105984 18060368
17:17:25 2871 8192000000 10168203312 10544226304 1257660272 13.309 100 87 174 0 1290416160 13518128 19237760
17:18:30 1033 8192000000 10196130032 10577842176 1283086272 13.541 100 90 178 0 1317425360 14160752 20178336
17:19:32 2214 8192000000 10202562272 10593800192 1293331296 13.635 100 73 145 0 1320232896 10909712 15991888
17:20:36 40331 8192000000 10199618576 10605432832 1305796624 13.748 100 43 85 0 1322670528 7041936 9831968
17:22:24 3427 8192000000 10208356400 10622836736 1320160304 13.878 100 29 57 0 1333271088 7427632 5683152
17:23:28 6278 8192000000 10211828112 10632896512 1330785600 13.974 100 26 51 1 1341996048 5580992 5629456
17:24:36 10346 8192000000 10214305024 10639314944 1333969120 14.003 100 14 29 1 1342485280 5738432 2777728
17:25:47 62437 8192000000 10229777488 10655023104 1342312832 14.078 100 33 66 0 1355880560 5907696 7660032
17:27:54 3022 8192000000 10232818896 10648084480 1337228112 14.032 100 35 66 0 1351212896 6495536 7489248
17:29:00 6094 8192000000 10190778848 10604679168 1292427776 13.626 99 31 62 0 1305634080 6131488 7074816
17:30:06 54391 8192000000 10161344448 10568417280 1265295376 13.379 98 35 71 0 1279308608 5627008 8386224
17:32:04 5365 8192000000 10135555504 10535563264 1229152016 13.046 98 21 43 0 1240055472 5848976 5054480
17:33:10 2947 8192000000 10143667168 10545344512 1253593312 13.271 100 23 45 0 1264412576 5817744 5001520
17:34:15 14218 8192000000 10178375984 10583470080 1281702912 13.529 100 23 44 1 1292466656 5337008 5426736
17:35:33 29791 8192000000 10174998992 10590703616 1289100864 13.596 100 21 40 0 1303179664 9510640 4568160
17:37:07 8420 8192000000 10199300656 10611253248 1305037296 13.741 100 32 63 1 1318121408 5761792 7322320
17:38:17 12391 8192000000 10212328048 10630131712 1323088192 13.905 100 12 24 0 1330225840 4780048 2357600
17:39:34 22940 8192000000 10253739232 10662600704 1343609872 14.090 100 42 82 1 1360593616 7910496 9073248
17:41:04 60013 8192000000 10258450496 10687311872 1358522912 14.224 100 25 48 0 1374624400 10987232 5114256
17:43:20 22482 8192000000 10294005008 10716401664 1377353232 14.393 100 45 88 0 1407266432 19248656 10664544
17:44:48 20080 8192000000 10305365696 10728873984 1385307760 14.464 100 56 112 0 1413182208 15157120 12717328
17:46:14 3550 8192000000 10319059152 10741035008 1394267136 14.544 100 58 114 1 1428451872 20595808 13588928
17:47:21 8581 8192000000 10314658384 10735882240 1392587200 14.529 100 37 72 0 1420282880 18534128 9161552
17:48:35 11967 8192000000 10333787136 10754142208 1399575072 14.591 99 64 122 2 1438735232 24955888 14204272
17:50:01 2370 8192000000 10367399424 10783313920 1424420688 14.812 100 81 163 0 1472730336 29108832 19200816
17:51:07 2637 8192000000 10352809888 10771730432 1414618576 14.725 100 65 128 2 1462478752 31025824 16834352
17:52:22 4488 8192000000 10336114352 10751520768 1400104320 14.596 100 67 130 1 1444391744 28442016 15845408
17:53:32 3225 8192000000 10334952304 10748706816 1382247696 14.437 99 65 131 0 1433732272 34412208 17072368
17:54:38 10706 8192000000 10303798368 10714767360 1361092128 14.247 99 65 129 0 1413773696 36161344 16520224
17:56:28 4200 8192000000 10320155184 10725163008 1370332800 14.330 100 68 134 0 1423743632 36040864 17369968
17:57:40 1246 8192000000 10336043712 10737188864 1373876720 14.362 100 88 171 3 1436344432 40812368 21655344
17:58:44 7732 8192000000 10326524576 10740260864 1376037168 14.381 100 49 98 2 1434038768 44207648 13793952
17:59:59 22191 8192000000 10329186752 10753269760 1363791328 14.271 98 52 102 0 1422765248 45268416 13705504
18:01:40 11445 8192000000 10308920912 10743582720 1367478928 14.304 99 32 64 0 1411822624 35636896 8706800
18:02:54 14799 8192000000 10299261776 10736979968 1357155008 14.212 100 28 56 0 1407053776 42106144 7792624
18:04:11 19384 8192000000 10290688896 10731888640 1342785472 14.083 99 15 30 0 1386484112 39287600 4411040
18:05:49 59471 8192000000 10274684864 10707857408 1350231296 14.150 99 23 44 0 1376432176 20540848 5660032
18:07:56 19189 8192000000 10262086080 10716954624 1356560624 14.206 100 21 43 0 1379052864 17079632 5412608
18:09:17 19554 8192000000 10267861264 10711269376 1357377424 14.214 99 54 107 4 1377601408 7711344 12512640
18:10:44 56551 8192000000 10267617280 10717642752 1358720992 14.226 99 30 59 0 1373753952 8903680 6129280
18:12:41 40819 8192000000 10280079344 10718867456 1362224976 14.257 99 42 83 2 1377852128 5992656 9634496
18:14:23 9938 8192000000 10299840208 10735984640 1366260432 14.294 99 44 87 0 1387347424 11204880 9882112
18:16:00 112181 8192000000 10280031440 10744348672 1375407344 14.375 99 15 30 0 1395337376 16347472 3582560
18:19:11 2029 8192000000 10303126576 10740334592 1390734048 14.512 100 54 104 0 1411856720 8279264 12843408
18:20:14 54165 8192000000 10296854032 10746769408 1382911296 14.443 99 16 33 0 1395642176 8993888 3736992
18:22:12 24241 8192000000 10311053664 10762833920 1402894496 14.621 100 25 48 0 1420628128 11726976 6006656
18:23:37 58695 8192000000 10295125728 10769063936 1395010752 14.551 100 4 8 0 1411578384 15799168 768464
18:25:36 30733 8192000000 10281384256 10735210496 1387169264 14.481 99 18 35 0 1397431232 6219472 4042496
18:27:09 100747 8192000000 10307275920 10765979648 1390851632 14.513 99 23 47 0 1410126816 13623696 5651488
18:30:04 29194 8192000000 10290427472 10748776448 1389448288 14.501 99 28 51 1 1403788016 5691152 8648576
18:31:36 7579 8192000000 10304689696 10760278016 1403889280 14.630 100 26 52 1 1417904304 7462384 6552640
18:32:47 45941 8192000000 10306817920 10769600512 1409520480 14.680 100 32 64 0 1431127840 13469936 8137424
18:34:38 52771 8192000000 10305197120 10774315008 1396120768 14.560 99 9 18 0 1413664336 15661056 1882512
18:36:36 68645 8192000000 10294977888 10766893056 1390731408 14.512 99 17 32 0 1407495168 13048800 3714960
18:38:45 38928 8192000000 10298337120 10740355072 1327252336 13.942 95 19 38 0 1338143696 6700480 4190880
18:40:29 49521 8192000000 10270806880 10717646848 1322896592 13.903 96 20 40 0 1336224064 9223552 4103920
18:42:22 36414 8192000000 10258750448 10689007616 1302128368 13.715 96 33 66 0 1318169632 7310480 8730784
18:44:04 36831 8192000000 10280568784 10707202048 1333503072 13.999 98 46 93 1 1355378320 10004912 11870336
18:45:42 52135 8192000000 10266766752 10714206208 1322240240 13.897 97 21 43 0 1342300512 15392032 4668240
18:47:37 104054 8192000000 10263336960 10704302080 1296734400 13.666 95 13 26 0 1315472816 15653152 3085264
18:50:23 16093 8192000000 10295103264 10725740544 1367423008 14.304 100 48 96 0 1394027648 16163216 10441424
18:51:40 185325 8192000000 10264962224 10718437376 1315616912 13.837 96 14 28 0 1348707200 28820704 4269584
18:56:59 56338 8192000000 10265379456 10708828160 1338994416 14.048 98 13 23 1 1351477040 10558656 1923968
18:59:00 40523 8192000000 10262095120 10705866752 1332648640 13.991 98 25 49 0 1346567504 8412208 5506656
19:00:50 63525 8192000000 10253148912 10703532032 1341624528 14.072 99 15 30 0 1355165280 9928448 3612304
19:03:14 37277 8192000000 10259955968 10714349568 1349889104 14.146 99 10 20 0 1362761008 10751632 2120272
19:04:53 51346 8192000000 10278938304 10730889216 1369150704 14.319 100 14 28 0 1390379600 18208992 3019904
19:06:45 47525 8192000000 10277767584 10730770432 1359840912 14.236 99 14 27 0 1377229696 14518000 2870784
19:08:44 61457 8192000000 10293108944 10745327616 1370603584 14.332 99 16 31 1 1388813504 14893568 3316352
19:10:52 43316 8192000000 10306280640 10764365824 1383949312 14.452 99 20 40 0 1402616784 14733472 3934000
19:12:48 74298 8192000000 10321208160 10780672000 1404046448 14.631 100 8 16 0 1425091568 19197680 1847440
19:15:15 84121 8192000000 10316784112 10769465344 1383959136 14.452 99 24 46 1 1403091712 14212544 4920032
19:17:40 38983 8192000000 10318459152 10756685824 1361588784 14.252 97 38 76 0 1379870480 10269744 8011952
19:19:22 76842 8192000000 10299352208 10734919680 1325585984 13.927 95 17 34 2 1339075776 9622608 3867184
19:21:39 99558 8192000000 10287890352 10739589120 1359074656 14.229 98 25 50 0 1374219696 9906768 5238272
19:24:23 40910 8192000000 10289069440 10743980032 1375635488 14.378 99 27 52 1 1393936240 11190752 7110000
19:26:09 30035 8192000000 10262185136 10729836544 1366703200 14.297 100 3 6 0 1379845824 12537328 605296
19:27:41 54653 8192000000 10257381296 10706276352 1316304992 13.843 96 11 22 0 1326071632 7242768 2523872
19:29:36 55483 8192000000 10247364784 10693763072 1326337072 13.934 98 16 32 0 1336437328 6902256 3198000
19:31:35 54875 8192000000 10259606960 10706141184 1355044848 14.193 100 14 28 0 1369448960 10852400 3551712
19:33:30 63259 8192000000 10233942400 10676670464 1261549680 13.344 93 15 30 0 1277984672 13133680 3301312
19:35:35 52164 8192000000 10260670624 10702331904 1334918784 14.012 99 25 50 0 1349746464 9186544 5641136
19:37:27 67727 8192000000 9529882704 9862135808 633576928 7.178 46 11 22 1 645053120 8921312 2554880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2022 19:39:56 to 11-12-2022 19:40:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

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          238601
MON$OLDEST_ACTIVE               238895
MON$OLDEST_SNAPSHOT             238895
MON$NEXT_TRANSACTION            238895
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                       2009216
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.33647 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33647 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33647 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33647 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 ---
:111: 
:112: mscs-test-161.budzdorov.ru    Sun Dec 11 19:38:44 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Sun Dec 11 19:38:46 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Sun Dec 11 19:38:47 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Sun Dec 11 19:38:56 2022
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:126: 
--- end of diff output ---

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

11.12.22 19:40:15. 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-11 19:40:18 No severe PSQL-related problems occured
11.12.22 19:40:19. End of report.