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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 17053128 kB
MemAvailable 31593044 kB
Buffers 0 kB
Cached 14655644 kB
SwapCached 0 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.8
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 2021-11-22 16:37:56
DTS_END                         2021-11-22 16:37:56.6440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43380
TRN_ID                          236829
ATT_ID                          695
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: 9117.80 22367 1094136
customer order: creation 173.68 360 20841
customer order: refuse 15.30 356 1836
order to supplier: creation 46.33 1272 5560
order to supplier: removal 3.38 104602 406
invoice (draft): creation 46.22 3184 5546
invoice (draft): removal 49.63 741 5955
invoice accept: apply 42.21 19039 5065
invoice accept: cancel 45.39 879 5447
customer reserve: creation 3929.82 187 471578
customer reserve: removal 4470.65 64 536478
realization accept: apply 83.38 182 10006
realization accept: cancel 80.35 108 9642
payment from customer: creation 60.29 104 7235
payment from customer: removal 5.71 81 685
payment to supplier: creation 49.46 131 5935
payment to supplier: removal 3.45 68 414
service: total inventory turnovers 4.66 962 559
service: total monetary turnovers 6.93 38 832
service: aggregate perf. data 0.93 16080 112
service: refresh index statistics 0.03 298910 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-11-2021 16:39:21 to 22-11-2021 16:39:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2481
TEST_TIME 2 4769
TEST_TIME 3 5083
TEST_TIME 4 4841
TEST_TIME 5 4820
TEST_TIME 6 4819
TEST_TIME 7 5279
TEST_TIME 8 5731
TEST_TIME 9 5940
TEST_TIME 10 6300
TEST_TIME 11 6699
TEST_TIME 12 6754
TEST_TIME 13 6932
TEST_TIME 14 7193
TEST_TIME 15 7222
TEST_TIME 16 7474
TEST_TIME 17 7914
TEST_TIME 18 8100
TEST_TIME 19 8376
TEST_TIME 20 9225
TEST_TIME 21 9016
TEST_TIME 22 9301
TEST_TIME 23 9770
TEST_TIME 24 9570
TEST_TIME 25 9842
TEST_TIME 26 10644
TEST_TIME 27 10498
TEST_TIME 28 10531
TEST_TIME 29 10669
TEST_TIME 30 10435
TEST_TIME 31 10489
TEST_TIME 32 10388
TEST_TIME 33 10220
TEST_TIME 34 10144
TEST_TIME 35 10024
TEST_TIME 36 9946
TEST_TIME 37 9900
TEST_TIME 38 9804
TEST_TIME 39 9669
TEST_TIME 40 9514
TEST_TIME 41 9486
TEST_TIME 42 9394
TEST_TIME 43 9352
TEST_TIME 44 9613
TEST_TIME 45 9450
TEST_TIME 46 9352
TEST_TIME 47 9512
TEST_TIME 48 9407
TEST_TIME 49 9399
TEST_TIME 50 9341
TEST_TIME 51 9305
TEST_TIME 52 9261
TEST_TIME 53 9217
TEST_TIME 54 9382
TEST_TIME 55 9376
TEST_TIME 56 9376
TEST_TIME 57 9360
TEST_TIME 58 9433
TEST_TIME 59 9494
TEST_TIME 60 9465
TEST_TIME 61 9549
TEST_TIME 62 9534
TEST_TIME 63 9557
TEST_TIME 64 9556
TEST_TIME 65 9415
TEST_TIME 66 9335
TEST_TIME 67 9360
TEST_TIME 68 9325
TEST_TIME 69 9328
TEST_TIME 70 9264
TEST_TIME 71 9203
TEST_TIME 72 9230
TEST_TIME 73 9228
TEST_TIME 74 9142
TEST_TIME 75 9193
TEST_TIME 76 9135
TEST_TIME 77 9138
TEST_TIME 78 9142
TEST_TIME 79 9178
TEST_TIME 80 9173
TEST_TIME 81 9216
TEST_TIME 82 9265
TEST_TIME 83 9250
TEST_TIME 84 9269
TEST_TIME 85 9391
TEST_TIME 86 9395
TEST_TIME 87 9395
TEST_TIME 88 9388
TEST_TIME 89 9339
TEST_TIME 90 9260
TEST_TIME 91 9224
TEST_TIME 92 9229
TEST_TIME 93 9187
TEST_TIME 94 9196
TEST_TIME 95 9178
TEST_TIME 96 9194
TEST_TIME 97 9191
TEST_TIME 98 9168
TEST_TIME 99 9204
TEST_TIME 100 9206
TEST_TIME 101 9174
TEST_TIME 102 9221
TEST_TIME 103 9228
TEST_TIME 104 9294
TEST_TIME 105 9256
TEST_TIME 106 9296
TEST_TIME 107 9308
TEST_TIME 108 9274
TEST_TIME 109 9308
TEST_TIME 110 9314
TEST_TIME 111 9260
TEST_TIME 112 9232
TEST_TIME 113 9215
TEST_TIME 114 9187
TEST_TIME 115 9174
TEST_TIME 116 9126
TEST_TIME 117 9105
TEST_TIME 118 9147
TEST_TIME 119 9120
TEST_TIME 120 9125

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-11-2021 16:39:25 to 22-11-2021 16:39:28.

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 1529445 1529445 0 0.00 0 13233 6 0 0
doc_list_biud 1529445 1529445 0 0.00 0 45556 28 0 0
sp_add_invoice_to_stock 5159 5065 94 1.82 48 179560 19039 0 94
sp_cancel_adding_invoice 5447 5447 0 0.00 4 70008 880 0 0
sp_cancel_client_order 1836 1836 0 0.00 4 10006 356 0 0
sp_cancel_customer_reserve 536478 536478 0 0.00 1 23735 64 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 2 4382 82 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 2 7100 69 0 0
sp_cancel_supplier_invoice 5965 5955 10 0.16 1 15368 741 0 10
sp_cancel_supplier_order 406 406 0 0.00 7 981839 104602 0 0
sp_cancel_write_off 9642 9642 0 0.00 1 10159 109 0 0
sp_client_order 20841 20841 0 0.00 3 11432 360 0 0
sp_customer_reserve 471578 471578 0 0.00 0 83958 187 0 0
sp_fill_shopping_cart 31945 31945 0 0.00 1 21538 36 0 0
sp_fill_shopping_cart_clo_res 471564 471564 0 0.00 0 15752 15 0 0
sp_get_clo_for_invoice 5040 5040 0 0.00 0 30772 330 0 0
sp_kill_cost_storno 16188 16188 0 0.00 0 11106 59 0 0
sp_kill_qstorno_ret_qs2qd 1088959 1088959 0 0.00 0 23730 34 0 0
sp_kill_qty_storno 574806 574806 0 0.00 0 45556 73 0 0
sp_lock_selected_doc 20926 20926 0 0.00 0 7681 6 0 0
sp_make_cost_storno 28212 28212 0 0.00 0 12909 54 0 0
sp_multiply_rows_for_pdistr 28212 28212 0 0.00 0 12338 30 0 0
sp_multiply_rows_for_qdistr 20841 20841 0 0.00 0 11423 157 0 0
sp_pay_from_customer 7235 7235 0 0.00 4 12253 104 0 0
sp_pay_to_supplier 5935 5935 0 0.00 5 22164 132 0 0
sp_payment_common 13170 13170 0 0.00 1 22084 32 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 0 9981 322 0 0
sp_qd_handle_on_invoice_upd_sts 10483 10483 0 0.00 0 45555 340 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 12168 54 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 14265 183 0 0
sp_supplier_invoice 5546 5546 0 0.00 7 81746 3185 0 0
sp_supplier_order 5560 5560 0 0.00 5 29581 1273 0 0
srv_aggregate_perf_data 112 112 0 0.00 2776 193354 16081 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1279 118202 44639 0 0
srv_find_qd_qs_mism 1044999 1044999 0 0.00 0 9631 6 0 0
srv_make_invnt_saldo 559 559 0 0.00 41 40006 962 0 0
srv_make_money_saldo 832 832 0 0.00 0 13249 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 177098 426554 298911 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 17367 181 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 3103 80 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 6253 14 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 2087 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 4886 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2920 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 5218 46 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 27004 1583 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 3056 9 0 0
t$perf-norm:sp_client_order 20841 20841 0 0.00 0 11806 18 0 0
t$perf-norm:sp_customer_reserve 899 899 0 0.00 0 1487 5 0 0
t$perf-norm:sp_pay_from_customer 7235 7235 0 0.00 0 15358 13 0 0
t$perf-norm:sp_pay_to_supplier 5935 5935 0 0.00 0 7635 17 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 15217 17 0 0
t$perf-norm:sp_supplier_invoice 5546 5546 0 0.00 0 8367 22 0 0
t$perf-norm:sp_supplier_order 5560 5560 0 0.00 0 12752 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1918 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 122 5 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1184 24 0 0
t$perf-norm:srv_make_money_saldo 832 832 0 0.00 0 1945 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10258 10070 188 1.83 0 8655 19 0 188
v_all_customers 38286 38286 0 0.00 0 15 1 0 0
v_all_suppliers 30844 30844 0 0.00 0 30 1 0 0
v_all_wares 41682 41682 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1204 7 0 0
v_cancel_client_order 3672 3672 0 0.00 0 2400 5 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 3852 11 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 2400 7 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 2166 6 0 0
v_cancel_supplier_invoice 828 808 20 2.41 0 539 14 0 20
v_cancel_supplier_order 812 812 0 0.00 0 497 4 0 0
v_cancel_write_off 2086 2086 0 0.00 0 2451 15 0 0
v_max_id_clo_ord 5558 5558 0 0.00 0 1580 1 0 0
v_max_id_clo_res 899 899 0 0.00 0 1146 11 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 3475 3 0 0
v_max_non_paid_invoice 1616 1616 0 0.00 0 325 2 0 0
v_max_non_paid_realizn 3818 3818 0 0.00 0 6139 8 0 0
v_min_id_clo_ord 5558 5558 0 0.00 0 3386 4 0 0
v_min_id_clo_res 899 899 0 0.00 0 3004 13 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 6374 9 0 0
v_min_non_paid_invoice 5935 5935 0 0.00 0 22072 29 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 10143 22 0 0
v_our_firm 10230 10230 0 0.00 0 68 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 5136 6 0 0
x_lock_depdocs_on_canc_invoice 10998 10998 0 0.00 0 20464 124 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 7988 309 0 0
x_make_qstorno_1000_1200 5560 5560 0 0.00 1 29569 1200 0 0
x_make_qstorno_1200_2000 5546 5546 0 0.00 3 79124 3058 0 0
x_make_qstorno_2100_3300 471578 471578 0 0.00 0 83958 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-11-2021 16:39:29 to 22-11-2021 16:39:33.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:00 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:01 10680 8192000000 9908491792 10217062400 994741264 10.828 100 88 176 0 1023470416 5427712 23301440
14:10:21 17107 8192000000 9939963584 10261024768 1023957392 11.110 100 89 178 0 1054533936 7935424 22641120
14:11:47 18361 8192000000 9974545392 10306584576 1060314688 11.459 100 85 170 1 1090904240 9467264 21122288
14:13:10 15966 8192000000 10011322464 10359881728 1105015824 11.885 100 72 145 0 1132285456 10564912 16704720
14:14:29 10601 8192000000 10081389136 10440548352 1163767296 12.439 100 75 150 0 1193022912 11628656 17626960
14:15:43 5579 8192000000 10113337680 10482782208 1202484240 12.799 100 71 142 1 1230874288 12778512 15611536
14:16:57 10203 8192000000 10160268048 10539261952 1251193776 13.249 100 80 160 0 1280729936 11945232 17590928
14:18:09 1913 8192000000 10199447760 10582052864 1285676976 13.565 100 88 176 2 1318695184 13683520 19334688
14:19:13 2826 8192000000 10218573472 10602242048 1299746928 13.693 100 89 175 0 1331894656 12968224 19179504
14:20:19 2000 8192000000 10232024336 10619539456 1310299472 13.789 100 93 184 3 1343141056 11799968 21041616
14:21:22 9659 8192000000 10207551568 10619768832 1313639472 13.819 100 29 55 2 1330064304 10760416 5664416
14:22:32 8731 8192000000 10208604208 10626478080 1323603696 13.909 100 27 54 1 1336752832 7037424 6111712
14:23:44 15101 8192000000 10223576656 10639785984 1333925184 14.003 100 44 89 1 1350611520 6694160 9992176
14:25:30 6463 8192000000 10226166672 10649960448 1337860032 14.038 100 24 47 1 1349123840 5882080 5381728
14:26:39 5167 8192000000 10226762992 10645934080 1329232592 13.960 99 18 36 0 1339180048 5736256 4211200
14:27:47 12659 8192000000 10231409728 10654216192 1333104912 13.995 99 12 24 0 1345755728 9922912 2727904
14:29:00 10378 8192000000 10209101600 10634887168 1308554320 13.773 98 12 24 1 1316014016 4659872 2799824
14:30:14 6505 8192000000 10164930832 10569814016 1236721008 13.116 96 37 70 3 1250682848 5419072 8542768
14:31:23 11081 8192000000 10136228688 10536185856 1197740160 12.755 95 19 38 1 1207611968 5346448 4525360
14:32:35 45544 8192000000 10138720208 10545713152 1203720592 12.811 95 20 40 1 1213513360 5420000 4372768
14:34:22 8428 8192000000 10176971040 10582401024 1283456704 13.545 100 23 44 2 1299210064 10283520 5469840
14:35:32 9309 8192000000 10182709184 10594828288 1294779536 13.648 100 18 36 1 1304284016 5018000 4486480
14:36:42 17716 8192000000 10198853392 10615390208 1307581792 13.764 100 21 42 1 1318615776 5487696 5546288
14:38:36 13116 8192000000 10235596912 10640351232 1326185520 13.933 100 59 117 2 1348731600 9798016 12748064
14:39:52 29958 8192000000 10265716096 10678059008 1359237168 14.231 100 51 102 0 1382909328 12457568 11214592
14:41:24 19261 8192000000 10282700928 10700693504 1374200464 14.365 100 51 101 2 1399780176 13314416 12265296
14:42:47 21631 8192000000 10293233312 10714882048 1384193232 14.454 100 38 76 0 1407444224 14742704 8508288
14:44:12 8029 8192000000 10310816496 10734166016 1395726192 14.557 100 47 94 0 1425328320 18462896 11139232
14:45:23 27952 8192000000 10320787248 10751537152 1403961232 14.630 100 43 87 2 1434178448 19849168 10368048
14:46:57 13012 8192000000 10341776704 10764734464 1414240320 14.722 100 55 109 0 1450041600 22514864 13286416
14:48:16 6154 8192000000 10350924688 10775482368 1418778448 14.762 100 65 130 0 1459401840 24808368 15815024
14:49:27 5550 8192000000 10372114112 10797518848 1428720560 14.850 100 65 130 0 1476450128 32546800 15182768
14:50:37 2169 8192000000 10376931184 10795503616 1424978752 14.817 100 79 158 0 1482008560 37813984 19215824
14:51:48 1277 8192000000 10376459456 10792992768 1420894032 14.781 100 72 144 0 1474130464 35398464 17837968
14:52:51 5244 8192000000 10363408176 10781466624 1400996496 14.604 99 68 135 4 1456703200 38106608 17600096
14:54:02 4845 8192000000 10357914592 10780033024 1406130240 14.650 100 56 112 0 1463903712 43682400 14091072
14:55:12 1686 8192000000 10349468128 10762117120 1389802608 14.504 100 74 148 0 1451849824 41951520 20095696
14:56:18 4064 8192000000 10340083728 10754662400 1370942576 14.335 98 70 137 1 1428369952 39891024 17536352
14:57:27 10198 8192000000 10321310752 10743169024 1369843904 14.326 99 40 80 1 1421623200 40735296 11044000
14:58:46 4431 8192000000 10300700400 10715414528 1343494224 14.089 99 42 84 1 1395349312 39978688 11876400
14:59:54 14855 8192000000 10281468912 10705100800 1335844064 14.020 98 35 72 0 1391440768 46055488 9541216
15:01:20 22897 8192000000 10274392416 10703183872 1335407744 14.016 99 26 53 1 1376711888 34195120 7109024
15:02:45 22750 8192000000 10286032448 10726699008 1336279904 14.024 99 23 46 0 1384569360 41389792 6899664
15:04:15 64200 8192000000 10272974288 10705268736 1313279232 13.816 97 33 66 0 1343829216 22160752 8389232
15:06:23 52421 8192000000 10250072400 10687660032 1332879472 13.993 99 27 53 1 1348645472 9922368 5843632
15:08:32 67349 8192000000 10259463344 10708242432 1351290384 14.159 99 24 50 1 1368014480 11469456 5254640
15:10:40 107550 8192000000 10283837696 10726735872 1363457856 14.268 99 30 60 1 1382186576 11600992 7127728
15:13:31 46229 8192000000 10292759728 10724925440 1348444576 14.133 98 53 107 4 1372751968 12616448 11690944
15:15:17 52742 8192000000 10272577648 10725044224 1375984080 14.381 100 17 34 1 1387934800 8195328 3755392
15:17:18 29854 8192000000 10302648720 10737778688 1351504656 14.161 97 44 89 0 1373075760 11900864 9670240
15:18:51 24467 8192000000 10317841120 10764906496 1404051744 14.631 100 25 51 1 1428308592 17661856 6594992
15:20:22 41390 8192000000 10335328496 10781409280 1408846608 14.674 100 46 92 0 1442863360 22152064 11864688
15:22:15 37927 8192000000 10312229168 10779426816 1406906480 14.656 100 16 32 0 1428518576 18003728 3608368
15:23:53 64840 8192000000 10312318720 10792898560 1408183664 14.668 100 17 34 0 1434332528 21959648 4189216
15:26:06 71264 8192000000 10294032848 10761015296 1392515264 14.528 99 11 22 0 1406597008 11700720 2381024
15:28:18 35891 8192000000 10325886400 10776580096 1410792368 14.691 100 39 78 0 1428687968 9208112 8687488
15:29:57 54359 8192000000 10332048352 10796019712 1420094080 14.774 100 16 31 0 1438331968 14694448 3543440
15:31:58 85356 8192000000 10326217104 10789564416 1405741904 14.646 99 10 20 0 1416893504 9180176 1971424
15:34:39 118201 8192000000 10325203984 10787827712 1354820704 14.191 96 10 20 0 1379098208 22222112 2055392
15:37:38 10501 8192000000 10326927424 10774953984 1409287552 14.678 100 53 103 1 1430323776 9008752 12027472
15:38:49 98526 8192000000 10309838112 10769051648 1334460160 14.007 94 11 22 0 1349109840 12320496 2329184
15:41:29 99745 8192000000 10295678320 10735915008 1350422464 14.151 97 24 48 1 1369202848 12981744 5798640
15:44:23 42722 8192000000 10269642864 10720153600 1319640064 13.873 95 26 52 2 1334622288 9313504 5668720
15:46:09 48563 8192000000 10258665168 10718646272 1347802768 14.128 99 9 18 0 1357891600 8493936 1594896
15:49:14 61497 8192000000 10256426208 10716561408 1345754704 14.109 98 12 25 0 1357716656 8762144 3199808
15:51:16 72930 8192000000 10258975536 10714079232 1330761856 13.974 97 14 29 0 1346329792 12257840 3310096
15:53:39 52303 8192000000 10249149760 10697781248 1294245504 13.643 95 6 12 1 1307683648 12196624 1241520
15:55:32 32632 8192000000 10232335504 10680582144 1303836704 13.730 97 17 34 2 1319731744 12125808 3769232
15:57:06 32425 8192000000 10258020304 10704269312 1341192928 14.068 99 26 52 0 1360182656 12563040 6426688
15:58:55 47053 8192000000 10278293600 10711306240 1345562288 14.108 99 27 55 0 1365434320 12920384 6951648
16:00:45 19612 8192000000 10277446784 10707165184 1338240160 14.042 98 45 90 0 1363057664 14601248 10216256
16:02:08 27744 8192000000 10288477792 10736873472 1357713904 14.217 99 34 68 1 1384608912 19395872 7499136
16:03:44 59220 8192000000 10278642304 10724052992 1352310816 14.168 99 22 44 1 1372623776 15197072 5115888
16:05:55 42747 8192000000 10279845312 10718007296 1328801424 13.956 97 37 74 0 1345593600 7961936 8830240
16:07:43 100715 8192000000 10282832672 10739073024 1377468368 14.394 100 28 56 1 1393861776 10253808 6139600
16:10:45 45750 8192000000 10288582208 10747035648 1363556400 14.269 98 21 42 0 1379060064 10875216 4628448
16:12:35 43004 8192000000 10295351088 10768179200 1393107104 14.534 100 6 12 0 1407335376 13011664 1216608
16:14:23 52283 8192000000 10286606320 10739560448 1341664624 14.072 96 10 20 0 1350239408 6506032 2068752
16:16:18 63607 8192000000 10299464736 10762821632 1370593120 14.332 98 14 28 0 1389930560 15996352 3341088
16:18:28 68778 8192000000 10311033888 10779340800 1402843280 14.620 100 20 40 1 1421894240 14566336 4484624
16:20:43 77792 8192000000 10298149968 10769256448 1385071520 14.462 99 9 18 0 1402081600 14984064 2026016
16:23:01 67931 8192000000 10306950416 10761994240 1355839424 14.200 97 15 30 0 1377916944 18963520 3114000
16:25:09 34270 8192000000 10312858080 10752495616 1360145424 14.239 97 28 56 1 1376415616 9993264 6276928
16:26:45 80527 8192000000 10290759888 10740445184 1339518352 14.053 96 25 50 1 1353925040 9778736 4627952
16:29:10 106179 8192000000 10297788704 10760749056 1382801168 14.442 99 19 38 0 1400835072 13626976 4406928
16:32:48 113317 8192000000 10262593072 10720841728 1323370272 13.907 95 11 22 0 1337248720 11223040 2655408
16:37:00 61303 8192000000 9138594208 9390313472 303438448 3.571 23 1 2 0 306921856 3441056 42352

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-11-2021 16:39:35 to 22-11-2021 16:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-11-2021 16:39:51 to 22-11-2021 16:39:53.

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          237098
MON$OLDEST_ACTIVE               237099
MON$OLDEST_SNAPSHOT             237099
MON$NEXT_TRANSACTION            237099
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                       2001408
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:182: 
:183: mscs-test-161.budzdorov.ru    Mon Nov 22 16:38:03 2021
:184:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:185: 
--- end of diff output ---

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

22.11.21 16:39:54. 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
2021-11-22 16:39:56 No severe PSQL-related problems occured
22.11.21 16:39:58. End of report.