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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15425296 kB
MemAvailable 30380464 kB
Buffers 0 kB
Cached 16260252 kB
SwapCached 3180 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 2023-03-18 01:38:40
DTS_END                         2023-03-18 01:38:40.5630
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42174
TRN_ID                          244154
ATT_ID                          614
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: 9380.94 17107 1125713
customer order: creation 179.44 363 21533
customer order: refuse 15.81 366 1897
order to supplier: creation 48.02 1281 5762
order to supplier: removal 3.51 83219 421
invoice (draft): creation 46.85 3105 5622
invoice (draft): removal 52.55 695 6306
invoice accept: apply 43.32 16514 5198
invoice accept: cancel 47.26 706 5671
customer reserve: creation 4009.08 164 481090
customer reserve: removal 4634.72 50 556166
realization accept: apply 85.40 172 10248
realization accept: cancel 82.01 106 9841
payment from customer: creation 61.40 100 7368
payment from customer: removal 5.74 50 689
payment to supplier: creation 50.01 122 6001
payment to supplier: removal 3.53 79 424
service: total inventory turnovers 4.93 1063 592
service: total monetary turnovers 6.47 21 776
service: aggregate perf. data 0.87 19271 104
service: refresh index statistics 0.03 214693 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-03-2023 01:39:32 to 18-03-2023 01:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2247
TEST_TIME 2 2951
TEST_TIME 3 3594
TEST_TIME 4 4037
TEST_TIME 5 4361
TEST_TIME 6 4900
TEST_TIME 7 4553
TEST_TIME 8 4798
TEST_TIME 9 5199
TEST_TIME 10 5479
TEST_TIME 11 5838
TEST_TIME 12 6236
TEST_TIME 13 7176
TEST_TIME 14 7587
TEST_TIME 15 7597
TEST_TIME 16 7629
TEST_TIME 17 8010
TEST_TIME 18 7980
TEST_TIME 19 8956
TEST_TIME 20 9247
TEST_TIME 21 9492
TEST_TIME 22 9729
TEST_TIME 23 10252
TEST_TIME 24 10539
TEST_TIME 25 10421
TEST_TIME 26 10254
TEST_TIME 27 10318
TEST_TIME 28 10224
TEST_TIME 29 9994
TEST_TIME 30 9879
TEST_TIME 31 9674
TEST_TIME 32 9552
TEST_TIME 33 9572
TEST_TIME 34 9512
TEST_TIME 35 9412
TEST_TIME 36 9381
TEST_TIME 37 9306
TEST_TIME 38 9259
TEST_TIME 39 9184
TEST_TIME 40 9079
TEST_TIME 41 9156
TEST_TIME 42 9111
TEST_TIME 43 9335
TEST_TIME 44 9383
TEST_TIME 45 9417
TEST_TIME 46 9447
TEST_TIME 47 9601
TEST_TIME 48 9596
TEST_TIME 49 9575
TEST_TIME 50 9592
TEST_TIME 51 9633
TEST_TIME 52 9587
TEST_TIME 53 9670
TEST_TIME 54 9662
TEST_TIME 55 9532
TEST_TIME 56 9467
TEST_TIME 57 9391
TEST_TIME 58 9360
TEST_TIME 59 9487
TEST_TIME 60 9384
TEST_TIME 61 9335
TEST_TIME 62 9258
TEST_TIME 63 9239
TEST_TIME 64 9201
TEST_TIME 65 9232
TEST_TIME 66 9178
TEST_TIME 67 9289
TEST_TIME 68 9359
TEST_TIME 69 9374
TEST_TIME 70 9358
TEST_TIME 71 9341
TEST_TIME 72 9468
TEST_TIME 73 9477
TEST_TIME 74 9422
TEST_TIME 75 9417
TEST_TIME 76 9497
TEST_TIME 77 9401
TEST_TIME 78 9357
TEST_TIME 79 9328
TEST_TIME 80 9281
TEST_TIME 81 9285
TEST_TIME 82 9214
TEST_TIME 83 9172
TEST_TIME 84 9162
TEST_TIME 85 9132
TEST_TIME 86 9132
TEST_TIME 87 9096
TEST_TIME 88 9149
TEST_TIME 89 9135
TEST_TIME 90 9169
TEST_TIME 91 9149
TEST_TIME 92 9197
TEST_TIME 93 9248
TEST_TIME 94 9260
TEST_TIME 95 9251
TEST_TIME 96 9330
TEST_TIME 97 9295
TEST_TIME 98 9356
TEST_TIME 99 9403
TEST_TIME 100 9400
TEST_TIME 101 9354
TEST_TIME 102 9335
TEST_TIME 103 9336
TEST_TIME 104 9281
TEST_TIME 105 9267
TEST_TIME 106 9238
TEST_TIME 107 9204
TEST_TIME 108 9164
TEST_TIME 109 9115
TEST_TIME 110 9117
TEST_TIME 111 9207
TEST_TIME 112 9203
TEST_TIME 113 9204
TEST_TIME 114 9176
TEST_TIME 115 9188
TEST_TIME 116 9219
TEST_TIME 117 9280
TEST_TIME 118 9271
TEST_TIME 119 9376
TEST_TIME 120 9386

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-03-2023 01:39:35 to 18-03-2023 01:39:39.

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 1569101 1569101 0 0.00 0 19440 6 0 0
doc_list_biud 1569093 1569093 0 0.00 0 24885 23 0 0
sp_add_invoice_to_stock 5335 5198 137 2.56 20 186017 16514 0 137
sp_cancel_adding_invoice 5671 5671 0 0.00 4 51119 706 0 0
sp_cancel_client_order 1897 1897 0 0.00 4 10783 366 0 0
sp_cancel_customer_reserve 556166 556166 0 0.00 0 19352 50 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 1964 51 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 4638 79 0 0
sp_cancel_supplier_invoice 6319 6306 13 0.20 1 15442 696 0 13
sp_cancel_supplier_order 421 421 0 0.00 5 797178 83219 0 0
sp_cancel_write_off 9841 9841 0 0.00 1 7206 107 0 0
sp_client_order 21533 21533 0 0.00 3 20792 363 0 0
sp_customer_reserve 481090 481090 0 0.00 0 44795 164 0 0
sp_fill_shopping_cart 32911 32911 0 0.00 1 11067 38 0 0
sp_fill_shopping_cart_clo_res 481079 481079 0 0.00 0 17250 15 0 0
sp_get_clo_for_invoice 5187 5187 0 0.00 0 34059 334 0 0
sp_kill_cost_storno 16625 16625 0 0.00 0 19430 43 0 0
sp_kill_qstorno_ret_qs2qd 1128900 1128900 0 0.00 0 19345 27 0 0
sp_kill_qty_storno 595732 595732 0 0.00 0 24883 60 0 0
sp_lock_selected_doc 21547 21547 0 0.00 0 8394 5 0 0
sp_make_cost_storno 28804 28804 0 0.00 0 15656 50 0 0
sp_multiply_rows_for_pdistr 28801 28801 0 0.00 0 17409 26 0 0
sp_multiply_rows_for_qdistr 21533 21533 0 0.00 0 13006 165 0 0
sp_pay_from_customer 7368 7368 0 0.00 4 13784 101 0 0
sp_pay_to_supplier 6001 6001 0 0.00 4 17418 122 0 0
sp_payment_common 13368 13368 0 0.00 1 9588 21 0 0
sp_qd_handle_on_cancel_clo 1897 1897 0 0.00 0 10726 332 0 0
sp_qd_handle_on_invoice_upd_sts 10853 10853 0 0.00 0 24880 282 0 0
sp_qd_handle_on_reserve_upd_sts 10248 10248 0 0.00 0 17451 59 0 0
sp_reserve_write_off 10248 10248 0 0.00 6 18336 172 0 0
sp_supplier_invoice 5622 5622 0 0.00 6 77097 3105 0 0
sp_supplier_order 5762 5762 0 0.00 4 33717 1282 0 0
srv_aggregate_perf_data 104 104 0 0.00 2843 311288 19272 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 4763 143992 48004 0 0
srv_find_qd_qs_mism 1075177 1075177 0 0.00 0 16802 5 0 0
srv_make_invnt_saldo 592 592 0 0.00 40 50241 1064 0 0
srv_make_money_saldo 776 776 0 0.00 0 525 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 123990 315398 214693 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5199 5199 0 0.00 0 20684 156 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 0 17706 137 0 0
t$perf-norm:sp_cancel_client_order 1897 1897 0 0.00 0 5418 18 0 0
t$perf-norm:sp_cancel_customer_reserve 686 686 0 0.00 0 2618 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1690 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 787 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 3688 31 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 24830 1552 0 0
t$perf-norm:sp_cancel_write_off 1108 1108 0 0.00 0 5978 12 0 0
t$perf-norm:sp_client_order 21533 21533 0 0.00 0 18484 20 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 6236 19 0 0
t$perf-norm:sp_pay_from_customer 7368 7368 0 0.00 0 10637 14 0 0
t$perf-norm:sp_pay_to_supplier 6001 6001 0 0.00 0 11153 20 0 0
t$perf-norm:sp_reserve_write_off 10248 10248 0 0.00 0 10552 14 0 0
t$perf-norm:sp_supplier_invoice 5622 5622 0 0.00 0 10440 24 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 12203 23 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 198 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 666 15 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 1975 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 3 0 0
v_add_invoice_to_stock 10636 10362 274 2.57 0 3901 18 0 274
v_all_customers 39154 39154 0 0.00 0 21 0 0 0
v_all_suppliers 31378 31378 0 0.00 0 24 1 0 0
v_all_wares 43064 43064 0 0.00 0 67 1 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 5476 14 0 0
v_cancel_client_order 3794 3794 0 0.00 0 3851 9 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 1943 7 0 0
v_cancel_customer_reserve 1372 1372 0 0.00 0 2284 9 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 4626 20 0 0
v_cancel_supplier_invoice 918 892 26 2.83 0 1359 17 0 26
v_cancel_supplier_order 842 842 0 0.00 0 3250 10 0 0
v_cancel_write_off 2216 2216 0 0.00 0 3195 19 0 0
v_max_id_clo_ord 5760 5760 0 0.00 0 1002 1 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 2946 16 0 0
v_max_id_ord_sup 5619 5619 0 0.00 0 5208 6 0 0
v_max_non_paid_invoice 1691 1691 0 0.00 0 620 3 0 0
v_max_non_paid_realizn 3976 3976 0 0.00 0 5091 6 0 0
v_min_id_clo_ord 5760 5760 0 0.00 0 3773 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 5248 13 0 0
v_min_id_ord_sup 5619 5619 0 0.00 0 5814 10 0 0
v_min_non_paid_invoice 6001 6001 0 0.00 0 9585 16 0 0
v_min_non_paid_realizn 7367 7367 0 0.00 0 8046 14 0 0
v_our_firm 10692 10692 0 0.00 0 29 1 0 0
v_reserve_write_off 20496 20496 0 0.00 0 4924 8 0 0
x_lock_depdocs_on_canc_invoice 11531 11531 0 0.00 0 20570 103 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 10523 339 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 33704 1212 0 0
x_make_qstorno_1200_2000 5622 5622 0 0.00 2 77087 2957 0 0
x_make_qstorno_2100_3300 481090 481090 0 0.00 0 44794 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-03-2023 01:39:40 to 18-03-2023 01:39:44.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:44 28164 8192000000 9911748208 10227101696 991576336 10.797 100 89 178 0 1021395392 6276880 23542176
23:11:15 12380 8192000000 9950963008 10275344384 1032758352 11.195 100 85 170 0 1063642640 9240864 21643424
23:12:33 12637 8192000000 9979346496 10313224192 1060213600 11.459 100 79 158 0 1089754544 10064192 19476752
23:13:53 10016 8192000000 10010120752 10354925568 1098803632 11.826 100 82 164 0 1130214912 11666400 19744880
23:15:05 6842 8192000000 10052786864 10405298176 1144015136 12.253 100 88 174 0 1176458592 11721632 20721824
23:16:14 5255 8192000000 10104018528 10467713024 1188329376 12.668 100 87 172 2 1221110160 13746544 19034240
23:17:22 3210 8192000000 10133169424 10504785920 1222261536 12.983 100 74 146 0 1251024304 12535888 16226880
23:18:26 1069 8192000000 10168265280 10544046080 1257302640 13.305 100 87 173 0 1289586224 13174336 19109248
23:19:29 1117 8192000000 10202740416 10585735168 1287839008 13.585 100 89 178 1 1322896528 14383408 20674112
23:20:32 4043 8192000000 10200760208 10594410496 1294986352 13.650 100 65 130 0 1319391616 9283488 15121776
23:21:37 5732 8192000000 10201796672 10600427520 1303854016 13.730 100 45 89 0 1321043936 7414144 9775776
23:22:47 10031 8192000000 10189678560 10609418240 1308769136 13.775 100 16 30 1 1317795792 5812416 3214240
23:23:57 48770 8192000000 10204330496 10624552960 1315087376 13.832 100 26 54 0 1326236784 4909408 6240000
23:25:52 3462 8192000000 10223767808 10640498688 1326874320 13.939 100 31 63 3 1340950816 7318960 6757536
23:26:56 6129 8192000000 10240353152 10657927168 1338785728 14.046 100 32 62 2 1352132240 6505824 6840688
23:28:03 50333 8192000000 10236364048 10640629760 1307450944 13.763 98 51 99 1 1325705040 5332912 12921184
23:29:53 9682 8192000000 10200862288 10615660544 1292378512 13.626 98 19 38 0 1301808368 5009120 4420736
23:31:04 5027 8192000000 10137341040 10540875776 1229630096 13.051 98 19 35 0 1238947184 5465024 3852064
23:32:10 5196 8192000000 10144482256 10540638208 1249692528 13.235 100 29 56 0 1262492880 6577120 6223232
23:33:15 25234 8192000000 10138348256 10548666368 1242929488 13.173 98 19 38 2 1252139024 5242176 3967360
23:34:42 7994 8192000000 10172843344 10579103744 1279262336 13.506 100 21 43 0 1289013856 4962128 4789392
23:35:50 6858 8192000000 10200077280 10606899200 1299047744 13.687 100 27 55 1 1311817248 5816832 6952672
23:37:04 56664 8192000000 10212389136 10618880000 1308823568 13.775 100 30 60 1 1321097504 5176912 7097024
23:39:05 29916 8192000000 10236924064 10652213248 1332950512 13.994 100 37 72 0 1346793232 5859856 7982864
23:40:40 21867 8192000000 10271146960 10683158528 1357883904 14.218 100 46 92 0 1380776896 12350944 10542048
23:42:09 24242 8192000000 10286700944 10707886080 1378594944 14.404 100 42 83 0 1403404032 14680800 10128288
23:43:40 33650 8192000000 10293177296 10723205120 1387836560 14.487 100 36 71 0 1411781712 14722592 9222560
23:45:18 39545 8192000000 10317532496 10749751296 1404989568 14.639 100 29 58 0 1432916528 20631552 7295408
23:47:21 19116 8192000000 10329113328 10762186752 1414166816 14.721 100 44 88 0 1447314448 22727632 10420000
23:48:43 4763 8192000000 10362411856 10780082176 1420227296 14.775 100 68 134 0 1465470752 29237584 16005872
23:49:59 7305 8192000000 10365188032 10784088064 1417878704 14.754 100 72 140 0 1472018256 37401536 16738016
23:51:15 9232 8192000000 10361591168 10791833600 1421297568 14.784 100 58 116 0 1471828048 35773440 14757040
23:52:42 4858 8192000000 10344206784 10775568384 1395442048 14.554 99 51 102 1 1445462592 36925792 13094752
23:53:52 8153 8192000000 10335451776 10764296192 1401062304 14.604 100 43 86 0 1442443760 29896352 11485104
23:55:05 5228 8192000000 10328524672 10751893504 1382170352 14.436 99 50 100 0 1431945152 36252624 13522176
23:56:12 9618 8192000000 10299026448 10715045888 1341101632 14.067 98 46 89 1 1387740768 34732128 11907008
23:57:28 7465 8192000000 10283688736 10713567232 1340968816 14.066 99 25 49 0 1384776400 37079808 6727776
23:58:44 24679 8192000000 10280204496 10706595840 1349499488 14.143 99 26 52 1 1383614832 27066176 7049168
00:00:29 16695 8192000000 10290682800 10716979200 1354290496 14.186 100 31 62 0 1394340912 31584320 8466096
00:01:49 58601 8192000000 10248656512 10695057408 1318720576 13.865 98 16 31 1 1354089600 30632736 4736288
00:04:00 47404 8192000000 10264629456 10701205504 1340868656 14.065 99 43 84 0 1363250000 12534032 9847312
00:05:55 12842 8192000000 10247830624 10696949760 1342842960 14.083 99 9 18 0 1361237360 16110336 2284064
00:07:10 83750 8192000000 10255295296 10703482880 1350860032 14.155 99 14 28 0 1361891136 7833440 3197664
00:09:54 27805 8192000000 10270556736 10710683648 1352780384 14.172 99 56 111 0 1371268320 6603792 11884144
00:11:22 58605 8192000000 10253764848 10703335424 1323716832 13.910 97 29 58 0 1341692432 11843248 6132352
00:13:22 54085 8192000000 10285274640 10732720128 1377240016 14.392 100 27 54 0 1395953696 13186656 5527024
00:15:19 19194 8192000000 10275133680 10712702976 1349015792 14.139 98 49 96 0 1369589008 8895088 11678128
00:16:39 26498 8192000000 10303525872 10749878272 1395460608 14.555 100 27 54 0 1416194304 14429792 6303904
00:18:15 99395 8192000000 10314455936 10780971008 1408080544 14.667 100 17 33 0 1437881584 25335568 4465472
00:21:06 49096 8192000000 10315352768 10763067392 1394685680 14.548 100 16 32 0 1427757712 28666496 4405536
00:23:01 38384 8192000000 10320119360 10772996096 1377217152 14.392 98 18 36 0 1396898112 15110224 4570736
00:24:45 62910 8192000000 10309242864 10769870848 1394802816 14.549 99 9 18 0 1415357728 18729360 1825552
00:26:54 26427 8192000000 10333367616 10775388160 1402053344 14.613 99 31 62 0 1421920896 13321136 6546416
00:28:21 63797 8192000000 10334045472 10788171776 1416054944 14.738 100 29 58 0 1432389600 10223488 6111168
00:30:30 59950 8192000000 10329775312 10790830080 1406586112 14.654 99 31 62 0 1429977840 16030576 7361152
00:32:32 143987 8192000000 10308540336 10765168640 1379649456 14.413 97 20 40 0 1396939920 12570416 4720048
00:36:00 12489 8192000000 10309112352 10756317184 1391809024 14.522 99 37 74 0 1411722288 11978608 7934656
00:37:13 88627 8192000000 10276736160 10747674624 1358964608 14.228 98 16 32 0 1374832736 12341776 3526352
00:39:42 31518 8192000000 10253181888 10708197376 1343627840 14.090 98 7 14 0 1351409632 6369120 1412672
00:41:37 43650 8192000000 10276877120 10727264256 1346223264 14.113 97 16 31 1 1357954224 8611440 3119520
00:43:28 35877 8192000000 10288347520 10732544000 1352185456 14.167 97 23 46 0 1369362912 11839824 5337632
00:45:08 70267 8192000000 10264107888 10721505280 1296607792 13.664 94 20 41 0 1322842992 21341248 4893952
00:47:23 55674 8192000000 10272633008 10712686592 1322704800 13.901 97 25 50 1 1336716384 8190064 5821520
00:49:36 88363 8192000000 10268651440 10725797888 1313777568 13.820 96 22 44 0 1342526256 23852608 4896080
00:52:07 13573 8192000000 10273665680 10698731520 1340635184 14.063 99 62 124 2 1361951872 6261360 15055328
00:53:24 15214 8192000000 10268030192 10697162752 1315101840 13.832 97 51 100 0 1338735952 13142784 10491328
00:55:23 98006 8192000000 10237773040 10677743616 1307238912 13.761 96 20 40 0 1323174880 11163584 4772384
00:59:04 72758 8192000000 10243865184 10700374016 1330349568 13.970 98 13 27 0 1341200880 8302704 2548608
01:02:27 83856 8192000000 10256710928 10710499328 1346804864 14.119 99 11 22 0 1356527552 7084016 2638672
01:04:55 42918 8192000000 10270396736 10725744640 1366512592 14.296 100 35 69 0 1384166368 10025744 7628032
01:06:39 49674 8192000000 10269606896 10714083328 1334716656 14.010 97 16 32 0 1347290896 8856528 3717712
01:08:36 65837 8192000000 10296160064 10745151488 1364026784 14.273 98 19 38 0 1379994192 11774416 4192992
01:10:41 96427 8192000000 10297147552 10763984896 1360906624 14.245 98 15 30 0 1380701920 16651728 3143568
01:13:19 25516 8192000000 10314309456 10764161024 1399756368 14.593 100 30 60 0 1417949088 11468720 6724000
01:14:51 78130 8192000000 10313570336 10765217792 1387246720 14.481 99 12 24 0 1405664048 15790736 2626592
01:17:14 92628 8192000000 10308964720 10744688640 1354410896 14.187 97 15 30 0 1372002720 14435184 3156640
01:19:47 82287 8192000000 10291654704 10750189568 1386724624 14.477 100 18 36 0 1398656848 7960976 3971248
01:22:10 34314 8192000000 10251801552 10702655488 1334045760 14.004 98 20 39 1 1348458432 10342208 4070464
01:23:46 76927 8192000000 10266053024 10725326848 1333449024 13.998 97 15 30 0 1349300016 12194448 3656544
01:26:12 72312 8192000000 10271850640 10722533376 1337966480 14.039 97 15 30 0 1354440720 12957968 3516272
01:28:36 77369 8192000000 10266768704 10725269504 1332291024 13.988 97 11 22 0 1343702080 8832256 2578800
01:30:55 33425 8192000000 10281589792 10732568576 1361157008 14.248 99 30 60 2 1377230176 9155504 6917664
01:32:36 61241 8192000000 10281801888 10733776896 1334049264 14.004 96 20 41 0 1358168496 19397408 4721824
01:34:54 103045 8192000000 10269070304 10718507008 1293821680 13.639 95 7 14 0 1307973216 12511232 1640304
01:37:37 75137 8192000000 9135119824 9376243712 293541264 3.459 22 4 8 0 301491200 7239024 710912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-03-2023 01:39:46 to 18-03-2023 01:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 18-03-2023 01:40:02 to 18-03-2023 01:40:04.

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          244454
MON$OLDEST_ACTIVE               244455
MON$OLDEST_SNAPSHOT             244455
MON$NEXT_TRANSACTION            244455
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                       2004800
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.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:405: 
:406: mscs-test-161.budzdorov.ru    Sat Mar 18 01:38:54 2023
:407:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:408: 
--- end of diff output ---

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

18.03.23 01:40:05. 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
2023-03-18 01:40:07 No severe PSQL-related problems occured
18.03.23 01:40:09. End of report.