Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.11.2021 19: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 16501184 kB
MemAvailable 31529228 kB
Buffers 0 kB
Cached 15137076 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-23 19:37:52
DTS_END                         2021-11-23 19:37:52.5780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/60654
TRN_ID                          241396
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: 9349.36 23449 1121923
customer order: creation 168.81 364 20257
customer order: refuse 14.57 343 1748
order to supplier: creation 45.40 1279 5448
order to supplier: removal 3.49 108835 419
invoice (draft): creation 44.57 3347 5348
invoice (draft): removal 54.11 862 6493
invoice accept: apply 41.24 18291 4949
invoice accept: cancel 48.83 801 5859
customer reserve: creation 3802.74 181 456329
customer reserve: removal 4836.14 61 580337
realization accept: apply 82.43 201 9891
realization accept: cancel 80.48 109 9658
payment from customer: creation 57.92 114 6950
payment from customer: removal 5.30 87 636
payment to supplier: creation 47.34 130 5681
payment to supplier: removal 3.38 80 405
service: total inventory turnovers 4.68 1180 562
service: total monetary turnovers 7.13 29 855
service: aggregate perf. data 0.78 18103 94
service: refresh index statistics 0.03 314585 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 19:38:36 to 23-11-2021 19:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2499
TEST_TIME 2 2542
TEST_TIME 3 4805
TEST_TIME 4 4574
TEST_TIME 5 4665
TEST_TIME 6 4809
TEST_TIME 7 4903
TEST_TIME 8 5040
TEST_TIME 9 5559
TEST_TIME 10 5723
TEST_TIME 11 5807
TEST_TIME 12 6362
TEST_TIME 13 6261
TEST_TIME 14 6161
TEST_TIME 15 6517
TEST_TIME 16 6520
TEST_TIME 17 6562
TEST_TIME 18 6628
TEST_TIME 19 6732
TEST_TIME 20 7005
TEST_TIME 21 7070
TEST_TIME 22 7440
TEST_TIME 23 7669
TEST_TIME 24 7960
TEST_TIME 25 8303
TEST_TIME 26 8747
TEST_TIME 27 9268
TEST_TIME 28 9835
TEST_TIME 29 10466
TEST_TIME 30 10943
TEST_TIME 31 10805
TEST_TIME 32 10785
TEST_TIME 33 10880
TEST_TIME 34 10765
TEST_TIME 35 10836
TEST_TIME 36 10711
TEST_TIME 37 10434
TEST_TIME 38 10272
TEST_TIME 39 10112
TEST_TIME 40 10088
TEST_TIME 41 10066
TEST_TIME 42 10159
TEST_TIME 43 10052
TEST_TIME 44 10013
TEST_TIME 45 9981
TEST_TIME 46 9891
TEST_TIME 47 9888
TEST_TIME 48 9801
TEST_TIME 49 9634
TEST_TIME 50 9534
TEST_TIME 51 9649
TEST_TIME 52 9538
TEST_TIME 53 9444
TEST_TIME 54 9378
TEST_TIME 55 9373
TEST_TIME 56 9456
TEST_TIME 57 9387
TEST_TIME 58 9303
TEST_TIME 59 9336
TEST_TIME 60 9280
TEST_TIME 61 9215
TEST_TIME 62 9197
TEST_TIME 63 9214
TEST_TIME 64 9315
TEST_TIME 65 9392
TEST_TIME 66 9417
TEST_TIME 67 9355
TEST_TIME 68 9365
TEST_TIME 69 9475
TEST_TIME 70 9448
TEST_TIME 71 9416
TEST_TIME 72 9533
TEST_TIME 73 9546
TEST_TIME 74 9527
TEST_TIME 75 9484
TEST_TIME 76 9428
TEST_TIME 77 9412
TEST_TIME 78 9415
TEST_TIME 79 9385
TEST_TIME 80 9305
TEST_TIME 81 9339
TEST_TIME 82 9312
TEST_TIME 83 9328
TEST_TIME 84 9297
TEST_TIME 85 9278
TEST_TIME 86 9304
TEST_TIME 87 9283
TEST_TIME 88 9277
TEST_TIME 89 9367
TEST_TIME 90 9457
TEST_TIME 91 9430
TEST_TIME 92 9500
TEST_TIME 93 9518
TEST_TIME 94 9621
TEST_TIME 95 9641
TEST_TIME 96 9695
TEST_TIME 97 9688
TEST_TIME 98 9645
TEST_TIME 99 9591
TEST_TIME 100 9536
TEST_TIME 101 9550
TEST_TIME 102 9568
TEST_TIME 103 9505
TEST_TIME 104 9495
TEST_TIME 105 9492
TEST_TIME 106 9448
TEST_TIME 107 9402
TEST_TIME 108 9389
TEST_TIME 109 9352
TEST_TIME 110 9287
TEST_TIME 111 9338
TEST_TIME 112 9313
TEST_TIME 113 9320
TEST_TIME 114 9313
TEST_TIME 115 9316
TEST_TIME 116 9321
TEST_TIME 117 9316
TEST_TIME 118 9375
TEST_TIME 119 9378
TEST_TIME 120 9349

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-11-2021 19:38:40 to 23-11-2021 19:38:43.

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 1544421 1544421 0 0.00 0 17087 6 0 0
doc_list_biud 1544418 1544418 0 0.00 0 41508 29 0 0
sp_add_invoice_to_stock 5032 4949 83 1.64 28 219112 18291 0 83
sp_cancel_adding_invoice 5859 5859 0 0.00 4 79205 801 0 0
sp_cancel_client_order 1748 1748 0 0.00 4 13822 343 0 0
sp_cancel_customer_reserve 580337 580337 0 0.00 1 21149 61 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 5496 87 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 2 6821 81 0 0
sp_cancel_supplier_invoice 6500 6493 7 0.10 1 41512 863 0 7
sp_cancel_supplier_order 419 419 0 0.00 7 1211401 108836 0 0
sp_cancel_write_off 9658 9658 0 0.00 1 13605 110 0 0
sp_client_order 20257 20257 0 0.00 3 28248 365 0 0
sp_customer_reserve 456329 456329 0 0.00 0 164989 181 0 0
sp_fill_shopping_cart 31048 31048 0 0.00 1 10175 36 0 0
sp_fill_shopping_cart_clo_res 456329 456329 0 0.00 0 13430 14 0 0
sp_get_clo_for_invoice 4949 4949 0 0.00 0 19436 305 0 0
sp_kill_cost_storno 16558 16558 0 0.00 0 17077 57 0 0
sp_kill_qstorno_ret_qs2qd 1177244 1177244 0 0.00 0 41506 33 0 0
sp_kill_qty_storno 619354 619354 0 0.00 0 41507 72 0 0
sp_lock_selected_doc 20525 20525 0 0.00 0 7234 5 0 0
sp_make_cost_storno 27469 27469 0 0.00 0 14864 64 0 0
sp_multiply_rows_for_pdistr 27469 27469 0 0.00 0 14649 32 0 0
sp_multiply_rows_for_qdistr 20257 20257 0 0.00 0 12550 155 0 0
sp_pay_from_customer 6950 6950 0 0.00 4 14832 115 0 0
sp_pay_to_supplier 5681 5681 0 0.00 5 8729 130 0 0
sp_payment_common 12628 12628 0 0.00 1 10046 28 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 13813 317 0 0
sp_qd_handle_on_invoice_upd_sts 10808 10808 0 0.00 0 20056 341 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 17889 72 0 0
sp_reserve_write_off 9891 9891 0 0.00 6 17914 202 0 0
sp_supplier_invoice 5348 5348 0 0.00 7 119918 3348 0 0
sp_supplier_order 5448 5448 0 0.00 6 33532 1280 0 0
srv_aggregate_perf_data 94 94 0 0.00 2507 197681 18104 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1570 214640 50215 0 0
srv_find_qd_qs_mism 1075054 1075054 0 0.00 0 16316 5 0 0
srv_make_invnt_saldo 562 562 0 0.00 43 41224 1181 0 0
srv_make_money_saldo 855 855 0 0.00 0 1253 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 85621 451683 314586 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4949 4949 0 0.00 0 17290 203 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 7639 99 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 6215 22 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2281 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 10976 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 1836 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 1403 15 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 37665 1814 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 7296 18 0 0
t$perf-norm:sp_client_order 20257 20257 0 0.00 0 22161 26 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 3738 10 0 0
t$perf-norm:sp_pay_from_customer 6950 6950 0 0.00 0 6874 13 0 0
t$perf-norm:sp_pay_to_supplier 5681 5681 0 0.00 0 6415 12 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 16663 18 0 0
t$perf-norm:sp_supplier_invoice 5348 5348 0 0.00 0 7175 26 0 0
t$perf-norm:sp_supplier_order 5448 5448 0 0.00 0 4315 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 568 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 335 7 0 0
t$perf-norm:srv_make_invnt_saldo 562 562 0 0.00 0 809 20 0 0
t$perf-norm:srv_make_money_saldo 855 855 0 0.00 0 1037 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10062 9896 166 1.64 0 6209 16 0 166
v_all_customers 36654 36654 0 0.00 0 65 1 0 0
v_all_suppliers 29988 29988 0 0.00 0 21 1 0 0
v_all_wares 40512 40512 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 4405 11 0 0
v_cancel_client_order 3496 3496 0 0.00 0 6205 6 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 4660 8 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1886 4 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 6501 12 0 0
v_cancel_supplier_invoice 802 788 14 1.74 0 1300 15 0 14
v_cancel_supplier_order 838 838 0 0.00 0 6980 23 0 0
v_cancel_write_off 1980 1980 0 0.00 0 4413 15 0 0
v_max_id_clo_ord 5445 5445 0 0.00 0 773 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 4585 11 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 6724 4 0 0
v_max_non_paid_invoice 1478 1478 0 0.00 0 613 3 0 0
v_max_non_paid_realizn 3653 3653 0 0.00 0 3136 4 0 0
v_min_id_clo_ord 5445 5445 0 0.00 0 4094 8 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 11833 16 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 4881 6 0 0
v_min_non_paid_invoice 5680 5680 0 0.00 0 8459 21 0 0
v_min_non_paid_realizn 6948 6948 0 0.00 0 10043 20 0 0
v_our_firm 10448 10448 0 0.00 0 29 1 0 0
v_reserve_write_off 19780 19780 0 0.00 0 6483 6 0 0
x_lock_depdocs_on_canc_invoice 11958 11958 0 0.00 0 36772 134 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 16334 424 0 0
x_make_qstorno_1000_1200 5448 5448 0 0.00 1 33449 1191 0 0
x_make_qstorno_1200_2000 5348 5348 0 0.00 2 119910 3232 0 0
x_make_qstorno_2100_3300 456329 456329 0 0.00 0 164987 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 19:38:44 to 23-11-2021 19:38:48.

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:07:56 5 8192000000 8798900528 8810168320 5314912 0.064 2 1 2 0 5414512 56816 42784
17:08:56 26733 8192000000 9888202800 10195537920 964788432 10.536 100 85 170 0 993271408 5818112 22664864
17:10:30 16881 8192000000 9935110432 10254475264 1015957312 11.033 100 91 182 0 1047414832 7721408 23736112
17:11:59 18990 8192000000 9983647680 10315128832 1063910496 11.494 100 88 176 1 1095142128 9542384 21689248
17:13:23 11231 8192000000 10036325792 10384396288 1122646272 12.052 100 81 162 1 1153153680 11290848 19216560
17:14:43 5632 8192000000 10093494944 10455752704 1180802848 12.598 100 69 138 0 1208162144 11520720 15838576
17:15:52 6031 8192000000 10138932384 10509709312 1223068448 12.990 100 83 166 0 1255251152 13268800 18913904
17:17:02 3298 8192000000 10166974272 10545283072 1256246880 13.296 100 83 163 2 1286908848 13416432 17245536
17:18:07 1662 8192000000 10216650976 10601627648 1297451040 13.672 100 92 184 0 1331603888 13681104 20471744
17:19:10 9488 8192000000 10227427056 10615189504 1306875728 13.758 100 74 149 1 1335837600 12394640 16567232
17:20:20 3445 8192000000 10210890560 10620399616 1314742592 13.829 100 38 76 1 1336232288 12287776 9201920
17:21:23 6426 8192000000 10201199232 10619813888 1319145888 13.869 100 24 47 2 1330384624 5951744 5286992
17:22:31 7714 8192000000 10207623968 10627780608 1325154512 13.923 100 24 48 1 1337125168 6582320 5388336
17:23:40 46670 8192000000 10218992064 10641313792 1328818032 13.956 100 34 68 0 1342767200 5773616 8175552
17:25:29 5844 8192000000 10227822688 10649595904 1335599904 14.018 100 31 62 1 1349633232 6666032 7367296
17:26:37 11234 8192000000 10214794960 10635956224 1325339872 13.925 100 13 25 2 1333767808 5604992 2822944
17:27:48 21370 8192000000 10191234096 10607775744 1253565696 13.271 95 16 32 0 1262433120 5343680 3523744
17:29:13 17346 8192000000 10146941424 10558234624 1192155584 12.703 93 28 56 0 1204046832 5034336 6856912
17:30:32 5846 8192000000 10143664224 10542055424 1209302400 12.863 96 34 67 2 1222740256 6101040 7336816
17:31:41 8980 8192000000 10154095472 10558386176 1251536688 13.252 99 28 56 2 1264095200 5753824 6804688
17:32:52 36676 8192000000 10162462160 10577342464 1268739088 13.410 99 35 70 0 1282611776 5751840 8120848
17:34:52 7085 8192000000 10195812240 10600738816 1292326704 13.625 100 37 74 1 1306774144 6462240 7985200
17:35:59 14922 8192000000 10190954720 10607329280 1300582624 13.701 100 22 44 1 1311373520 5620304 5170592
17:37:16 8037 8192000000 10212536160 10631090176 1323146464 13.905 100 17 34 1 1331543184 4477184 3919536
17:38:28 10061 8192000000 10248542928 10657992704 1337702448 14.037 100 48 94 1 1360415024 11781840 10930736
17:39:42 21920 8192000000 10265727728 10680291328 1348042240 14.130 100 63 125 1 1375234448 12889136 14303072
17:41:15 26231 8192000000 10282815136 10704195584 1364192304 14.275 100 47 94 1 1392953424 18385856 10375264
17:42:47 15170 8192000000 10282939584 10710233088 1374144928 14.364 100 51 100 3 1401363776 14464992 12753856
17:44:07 20689 8192000000 10306038272 10730315776 1385260192 14.464 100 56 112 0 1416041104 17626544 13154368
17:45:40 10800 8192000000 10325762944 10746757120 1394617680 14.547 100 57 112 0 1430827344 23749344 12460320
17:46:58 6996 8192000000 10328638368 10756091904 1404062112 14.631 100 48 95 1 1443364624 28012048 11290464
17:48:09 4217 8192000000 10335721792 10754351104 1396309808 14.562 100 66 132 0 1444294448 32027568 15957072
17:49:21 14870 8192000000 10341931328 10767364096 1396019040 14.560 99 48 96 1 1442876832 34651168 12206624
17:50:39 9254 8192000000 10348288176 10769674240 1405283264 14.642 100 59 119 1 1456201504 36736576 14181664
17:52:01 1934 8192000000 10381611344 10796617728 1423392928 14.803 100 81 162 0 1487393632 43799184 20201520
17:53:08 8868 8192000000 10373335824 10790514688 1422340112 14.793 100 65 128 0 1478437600 39952688 16144800
17:54:20 1668 8192000000 10360081872 10764320768 1393025472 14.533 99 87 174 0 1460780448 46779648 20975328
17:55:26 2395 8192000000 10358347504 10757738496 1387207248 14.481 100 94 189 1 1459897552 48803680 23886624
17:56:37 1568 8192000000 10365796528 10764177408 1383681248 14.449 99 88 176 1 1460285248 53205280 23398720
17:57:46 4248 8192000000 10355628784 10758676480 1376499120 14.385 99 82 162 1 1456526144 58306016 21721008
17:58:58 5279 8192000000 10373066416 10776662016 1387048944 14.480 100 82 161 2 1473757792 65502496 21206352
18:00:07 9578 8192000000 10382063632 10800517120 1398795968 14.584 100 54 108 1 1479416832 65710336 14910528
18:01:28 7622 8192000000 10363034736 10783715328 1386160528 14.472 100 52 103 1 1468626944 67913040 14553376
18:02:38 11918 8192000000 10382247216 10810191872 1383502608 14.448 99 44 88 1 1475281088 79083040 12695440
18:03:56 20908 8192000000 10364515712 10801905664 1390347280 14.509 100 30 60 2 1469015040 69628848 9038912
18:05:36 22941 8192000000 10322743248 10756616192 1364351104 14.276 99 33 65 0 1414072624 41368160 8353360
18:07:30 45392 8192000000 10301634464 10741542912 1378686544 14.405 100 13 26 1 1397269600 16099872 2483184
18:09:28 5087 8192000000 10297290992 10736115712 1381953776 14.434 100 59 119 1 1406013248 9883104 14176368
18:10:40 82332 8192000000 10294918544 10740248576 1328787632 13.956 96 9 18 0 1346217696 15488080 1941984
18:13:03 116352 8192000000 10312629424 10744545280 1372672400 14.351 99 38 74 0 1395940400 14799600 8468400
18:16:08 71864 8192000000 10320013552 10770071552 1400452352 14.599 100 45 89 1 1427025216 16408576 10164288
18:18:31 76311 8192000000 10298708944 10767249408 1382190768 14.436 99 10 20 0 1400660912 16285824 2184320
18:20:50 82016 8192000000 10298864832 10761093120 1397954640 14.577 100 18 36 0 1412346256 10108976 4282640
18:23:17 214637 8192000000 10307188432 10774818816 1404082528 14.631 100 14 28 0 1416732864 9229136 3421200
18:27:56 114306 8192000000 10305882528 10772291584 1368634128 14.315 97 15 30 0 1393005392 20794384 3576880
18:30:55 55753 8192000000 10301160944 10762645504 1391730928 14.521 99 24 48 0 1410826224 13240288 5855008
18:32:57 42978 8192000000 10280033232 10747977728 1371210112 14.338 98 8 16 0 1383055968 9939680 1906176
18:34:43 105166 8192000000 10301246000 10764599296 1391746384 14.521 99 28 56 2 1409746432 11805360 6194688
18:38:33 68716 8192000000 10264717280 10724638720 1320131856 13.878 95 6 12 1 1328085072 6904160 1049056
18:40:41 48459 8192000000 10275520128 10749517824 1342271856 14.078 97 10 20 0 1353824336 9431216 2121264
18:42:45 100458 8192000000 10261437200 10718789632 1236023856 13.110 89 13 26 0 1247300400 8601856 2674688
18:45:25 75439 8192000000 10269533632 10735370240 1323446768 13.908 96 24 46 3 1343527216 14902560 5177888
18:48:05 36541 8192000000 10270148640 10704531456 1325832864 13.929 97 47 94 1 1350153024 13865792 10454368
18:49:43 71330 8192000000 10273036992 10720309248 1336330800 14.024 98 11 21 0 1352775792 13415904 3029088
18:51:55 62409 8192000000 10262104240 10719154176 1339058192 14.049 98 14 28 0 1356078256 14017424 3002640
18:53:57 79701 8192000000 10255360864 10712334336 1333316976 13.997 98 23 46 1 1355165856 16286496 5562384
18:56:18 44829 8192000000 10255701280 10712567808 1360764624 14.244 100 15 30 0 1371771088 7773264 3233200
18:58:03 56433 8192000000 10264249760 10729349120 1368936784 14.318 100 15 30 1 1383284880 9925632 4422464
19:00:24 90117 8192000000 10258140544 10713669632 1311127088 13.796 96 16 32 0 1324181728 9326992 3727648
19:02:56 53260 8192000000 10262575296 10719064064 1351020384 14.157 99 20 40 0 1367776096 12159360 4596352
19:04:50 47921 8192000000 10285717104 10741436416 1379351296 14.411 100 26 52 0 1400739184 15288192 6099696
19:06:39 76813 8192000000 10290043744 10754998272 1358254976 14.222 99 12 24 0 1378381872 17637552 2489344
19:09:48 74442 8192000000 10308668816 10774654976 1396913504 14.568 100 12 24 0 1413824320 14354096 2556720
19:12:03 163495 8192000000 10330668912 10783133696 1374315760 14.366 98 13 26 1 1393149680 16303968 2529952
19:16:57 53329 8192000000 10315523520 10772561920 1404631584 14.636 100 34 68 1 1426928656 14846512 7450560
19:18:50 51280 8192000000 10297576144 10771320832 1381978880 14.434 99 15 30 1 1402815056 17873024 2963152
19:21:35 93318 8192000000 10276950288 10749763584 1360756432 14.244 98 13 26 0 1377528672 13357616 3414624
19:24:08 69788 8192000000 10289630592 10744586240 1373954160 14.362 99 29 59 2 1390677424 9556160 7167104
19:26:20 90605 8192000000 10273119120 10733092864 1349808224 14.146 98 11 22 0 1366037072 13902160 2326688
19:29:14 64806 8192000000 10273903936 10739740672 1366029184 14.291 99 5 10 0 1379985504 12863664 1092656
19:31:20 49963 8192000000 10237327872 10692222976 1274424032 13.462 93 22 44 1 1289805120 10437504 4943584
19:33:14 38224 8192000000 10258223424 10700222464 1306673840 13.756 96 37 74 0 1324793856 10279696 7840320
19:34:55 69009 8192000000 10247924432 10709569536 1285343392 13.562 94 23 46 0 1306222032 15951184 4927456
19:37:04 30803 8192000000 10271020192 10710835200 1345754512 14.109 99 30 60 0 1360758976 9007808 5996656

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-11-2021 19:38:50 to 23-11-2021 19:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 19:39:06 to 23-11-2021 19:39:08.

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          239836
MON$OLDEST_ACTIVE               241513
MON$OLDEST_SNAPSHOT             241513
MON$NEXT_TRANSACTION            241513
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                       2061856
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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Tue Nov 23 19:37:53 2021
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Tue Nov 23 19:37:55 2021
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:250: 
--- end of diff output ---

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

23.11.21 19:39:09. 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-23 19:39:11 No severe PSQL-related problems occured
23.11.21 19:39:12. End of report.