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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16507716 kB
MemAvailable 31479304 kB
Buffers 0 kB
Cached 15225740 kB
SwapCached 2660 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-05-13 19:38:18
DTS_END                         2022-05-13 19:38:18.6370
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43204
TRN_ID                          247062
ATT_ID                          725
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: 9206.80 19454 1104816
customer order: creation 178.63 366 21435
customer order: refuse 15.25 331 1830
order to supplier: creation 47.70 1328 5724
order to supplier: removal 3.49 102480 419
invoice (draft): creation 47.16 3397 5659
invoice (draft): removal 52.42 942 6290
invoice accept: apply 42.21 17340 5065
invoice accept: cancel 46.48 847 5578
customer reserve: creation 3891.98 171 467037
customer reserve: removal 4587.42 60 550490
realization accept: apply 85.44 205 10253
realization accept: cancel 75.55 125 9066
payment from customer: creation 61.41 131 7369
payment from customer: removal 5.55 96 666
payment to supplier: creation 50.35 149 6042
payment to supplier: removal 3.63 64 435
service: total inventory turnovers 4.63 1095 556
service: total monetary turnovers 6.65 20 798
service: aggregate perf. data 0.83 23705 100
service: refresh index statistics 0.03 236219 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-05-2022 19:39:40 to 13-05-2022 19:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6151
TEST_TIME 2 4864
TEST_TIME 3 4952
TEST_TIME 4 4357
TEST_TIME 5 4222
TEST_TIME 6 4604
TEST_TIME 7 5151
TEST_TIME 8 5395
TEST_TIME 9 5305
TEST_TIME 10 5412
TEST_TIME 11 5834
TEST_TIME 12 6495
TEST_TIME 13 6590
TEST_TIME 14 6800
TEST_TIME 15 6941
TEST_TIME 16 7113
TEST_TIME 17 7416
TEST_TIME 18 7801
TEST_TIME 19 7981
TEST_TIME 20 8116
TEST_TIME 21 8202
TEST_TIME 22 8175
TEST_TIME 23 8678
TEST_TIME 24 8976
TEST_TIME 25 9450
TEST_TIME 26 9652
TEST_TIME 27 9926
TEST_TIME 28 10399
TEST_TIME 29 11351
TEST_TIME 30 11093
TEST_TIME 31 10956
TEST_TIME 32 10661
TEST_TIME 33 10740
TEST_TIME 34 10734
TEST_TIME 35 10522
TEST_TIME 36 10434
TEST_TIME 37 10302
TEST_TIME 38 10310
TEST_TIME 39 10256
TEST_TIME 40 10192
TEST_TIME 41 10104
TEST_TIME 42 10043
TEST_TIME 43 9972
TEST_TIME 44 9896
TEST_TIME 45 9862
TEST_TIME 46 9689
TEST_TIME 47 9802
TEST_TIME 48 9834
TEST_TIME 49 9869
TEST_TIME 50 9744
TEST_TIME 51 9785
TEST_TIME 52 9758
TEST_TIME 53 9657
TEST_TIME 54 9708
TEST_TIME 55 9716
TEST_TIME 56 9785
TEST_TIME 57 9749
TEST_TIME 58 9827
TEST_TIME 59 9766
TEST_TIME 60 9869
TEST_TIME 61 9914
TEST_TIME 62 9869
TEST_TIME 63 9978
TEST_TIME 64 9962
TEST_TIME 65 9900
TEST_TIME 66 10055
TEST_TIME 67 9914
TEST_TIME 68 9802
TEST_TIME 69 9725
TEST_TIME 70 9790
TEST_TIME 71 9712
TEST_TIME 72 9684
TEST_TIME 73 9649
TEST_TIME 74 9584
TEST_TIME 75 9518
TEST_TIME 76 9543
TEST_TIME 77 9473
TEST_TIME 78 9427
TEST_TIME 79 9442
TEST_TIME 80 9537
TEST_TIME 81 9548
TEST_TIME 82 9519
TEST_TIME 83 9539
TEST_TIME 84 9513
TEST_TIME 85 9563
TEST_TIME 86 9567
TEST_TIME 87 9565
TEST_TIME 88 9610
TEST_TIME 89 9651
TEST_TIME 90 9614
TEST_TIME 91 9540
TEST_TIME 92 9490
TEST_TIME 93 9452
TEST_TIME 94 9454
TEST_TIME 95 9423
TEST_TIME 96 9394
TEST_TIME 97 9359
TEST_TIME 98 9305
TEST_TIME 99 9315
TEST_TIME 100 9312
TEST_TIME 101 9287
TEST_TIME 102 9306
TEST_TIME 103 9350
TEST_TIME 104 9422
TEST_TIME 105 9399
TEST_TIME 106 9401
TEST_TIME 107 9407
TEST_TIME 108 9392
TEST_TIME 109 9422
TEST_TIME 110 9445
TEST_TIME 111 9511
TEST_TIME 112 9464
TEST_TIME 113 9401
TEST_TIME 114 9366
TEST_TIME 115 9303
TEST_TIME 116 9321
TEST_TIME 117 9291
TEST_TIME 118 9287
TEST_TIME 119 9259
TEST_TIME 120 9214

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-05-2022 19:39:43 to 13-05-2022 19:39:47.

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 1538349 1538349 0 0.00 0 24097 6 0 0
doc_list_biud 1538348 1538348 0 0.00 0 55901 28 0 0
sp_add_invoice_to_stock 5175 5065 110 2.12 39 169680 17341 0 110
sp_cancel_adding_invoice 5578 5578 0 0.00 4 63987 848 0 0
sp_cancel_client_order 1830 1830 0 0.00 4 12114 331 0 0
sp_cancel_customer_reserve 550490 550490 0 0.00 1 24639 61 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 2 7092 96 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 1814 65 0 0
sp_cancel_supplier_invoice 6299 6290 9 0.14 2 55985 942 0 9
sp_cancel_supplier_order 419 419 0 0.00 9 1164524 102481 0 0
sp_cancel_write_off 9066 9066 0 0.00 1 39571 126 0 0
sp_client_order 21435 21435 0 0.00 3 24714 366 0 0
sp_customer_reserve 467037 467037 0 0.00 0 80541 172 0 0
sp_fill_shopping_cart 32799 32799 0 0.00 1 8988 35 0 0
sp_fill_shopping_cart_clo_res 467027 467027 0 0.00 0 24414 13 0 0
sp_get_clo_for_invoice 5050 5050 0 0.00 0 25921 274 0 0
sp_kill_cost_storno 15745 15745 0 0.00 0 17002 59 0 0
sp_kill_qstorno_ret_qs2qd 1116755 1116755 0 0.00 0 55898 34 0 0
sp_kill_qty_storno 588976 588976 0 0.00 0 55900 73 0 0
sp_lock_selected_doc 21264 21264 0 0.00 0 8964 8 0 0
sp_make_cost_storno 28714 28714 0 0.00 0 19757 63 0 0
sp_multiply_rows_for_pdistr 28714 28714 0 0.00 0 17105 33 0 0
sp_multiply_rows_for_qdistr 21435 21435 0 0.00 0 19128 153 0 0
sp_pay_from_customer 7369 7369 0 0.00 4 19443 132 0 0
sp_pay_to_supplier 6042 6042 0 0.00 4 20346 149 0 0
sp_payment_common 13411 13411 0 0.00 1 19423 37 0 0
sp_qd_handle_on_cancel_clo 1830 1830 0 0.00 0 12106 290 0 0
sp_qd_handle_on_invoice_upd_sts 10628 10628 0 0.00 0 35191 341 0 0
sp_qd_handle_on_reserve_upd_sts 10253 10253 0 0.00 0 15104 64 0 0
sp_reserve_write_off 10253 10253 0 0.00 6 19711 205 0 0
sp_supplier_invoice 5659 5659 0 0.00 7 84674 3397 0 0
sp_supplier_order 5724 5724 0 0.00 4 51700 1328 0 0
srv_aggregate_perf_data 100 100 0 0.00 2292 394789 23706 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2084 152973 46682 0 0
srv_find_qd_qs_mism 1056418 1056418 0 0.00 0 24094 5 0 0
srv_make_invnt_saldo 556 556 0 0.00 33 54080 1095 0 0
srv_make_money_saldo 798 798 0 0.00 1 378 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 134212 331988 236220 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5065 5065 0 0.00 0 14154 146 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 2500 63 0 0
t$perf-norm:sp_cancel_client_order 1830 1830 0 0.00 0 5772 14 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 2165 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 3559 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 4464 28 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1504 16 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 26716 1626 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 3313 10 0 0
t$perf-norm:sp_client_order 21435 21435 0 0.00 0 14619 20 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 3943 11 0 0
t$perf-norm:sp_pay_from_customer 7369 7369 0 0.00 0 6880 9 0 0
t$perf-norm:sp_pay_to_supplier 6042 6042 0 0.00 0 6868 14 0 0
t$perf-norm:sp_reserve_write_off 10253 10253 0 0.00 0 17146 13 0 0
t$perf-norm:sp_supplier_invoice 5659 5659 0 0.00 0 6730 18 0 0
t$perf-norm:sp_supplier_order 5724 5724 0 0.00 0 3564 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 301 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 90 2 0 0
t$perf-norm:srv_make_invnt_saldo 556 556 0 0.00 0 789 19 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 1897 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 446 113 0 0
v_add_invoice_to_stock 10320 10100 220 2.13 0 11376 19 0 220
v_all_customers 39362 39362 0 0.00 0 14 1 0 0
v_all_suppliers 31586 31586 0 0.00 0 98 1 0 0
v_all_wares 42870 42870 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 742 8 0 0
v_cancel_client_order 3660 3660 0 0.00 0 11894 11 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 7063 12 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 471 3 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 595 4 0 0
v_cancel_supplier_invoice 848 830 18 2.12 0 623 14 0 18
v_cancel_supplier_order 838 838 0 0.00 0 390 4 0 0
v_cancel_write_off 2124 2124 0 0.00 0 2987 14 0 0
v_max_id_clo_ord 5716 5716 0 0.00 0 838 1 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2722 11 0 0
v_max_id_ord_sup 5648 5648 0 0.00 0 4568 4 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 334 3 0 0
v_max_non_paid_realizn 3716 3716 0 0.00 0 3182 5 0 0
v_min_id_clo_ord 5716 5716 0 0.00 0 4411 7 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 8639 17 0 0
v_min_id_ord_sup 5648 5648 0 0.00 0 6431 9 0 0
v_min_non_paid_invoice 6042 6042 0 0.00 0 9797 25 0 0
v_min_non_paid_realizn 7369 7369 0 0.00 0 19416 32 0 0
v_our_firm 10814 10814 0 0.00 0 21 1 0 0
v_reserve_write_off 20504 20504 0 0.00 0 11364 6 0 0
x_lock_depdocs_on_canc_invoice 11453 11453 0 0.00 0 24228 112 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 4999 357 0 0
x_make_qstorno_1000_1200 5724 5724 0 0.00 1 51694 1247 0 0
x_make_qstorno_1200_2000 5659 5659 0 0.00 3 84617 3276 0 0
x_make_qstorno_2100_3300 467037 467037 0 0.00 0 80539 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-05-2022 19:39:48 to 13-05-2022 19:39:52.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:22 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:23 26588 8192000000 9889664864 10197671936 967142448 10.559 100 92 184 0 997299552 5593408 24563696
17:10:55 20154 8192000000 9950175712 10268045312 1025830736 11.128 100 89 178 0 1056896096 7812304 23253056
17:12:24 10871 8192000000 9976557344 10309840896 1065138848 11.506 100 77 154 0 1093657920 9161152 19357920
17:13:39 12690 8192000000 10041297824 10390605824 1127132432 12.094 100 79 158 1 1156965824 10710960 19122432
17:15:01 10716 8192000000 10093657904 10453278720 1175114960 12.545 100 76 152 0 1204390128 11893808 17381360
17:16:15 6961 8192000000 10131719008 10500673536 1219196368 12.954 100 76 152 1 1248808880 12769328 16843184
17:17:25 5630 8192000000 10156414640 10535022592 1244534288 13.188 100 74 146 0 1273807040 13518560 15754192
17:18:32 1576 8192000000 10200851600 10582519808 1287344576 13.580 100 93 187 1 1322467392 14443968 20678848
17:19:35 2395 8192000000 10216720208 10602291200 1299552848 13.691 100 91 181 1 1332431552 10735408 22143296
17:20:38 6833 8192000000 10206975680 10610360320 1311366128 13.798 100 38 76 2 1327048112 7469648 8212336
17:21:46 37038 8192000000 10225726032 10627375104 1317131440 13.851 100 60 121 0 1338653568 5794272 15727856
17:23:24 4558 8192000000 10222846256 10637750272 1327911312 13.948 100 34 70 1 1343555536 8022688 7621536
17:24:29 7583 8192000000 10224113872 10641747968 1335051792 14.013 100 14 27 0 1343356448 5457760 2846896
17:25:39 10575 8192000000 10241053648 10661384192 1343250112 14.087 100 28 56 0 1360695424 9916448 7528864
17:26:53 13595 8192000000 10227189232 10654756864 1339341104 14.051 100 22 44 0 1349404080 4778064 5284912
17:28:09 7379 8192000000 10219095872 10638340096 1323882368 13.912 99 31 62 1 1337067904 6054992 7130544
17:29:17 8866 8192000000 10151777472 10563416064 1211629984 12.884 93 10 20 0 1219112080 5232336 2249760
17:30:26 59665 8192000000 10152296832 10552225792 1196872432 12.747 94 29 58 0 1209086976 5303632 6910912
17:32:26 7860 8192000000 10146195712 10550267904 1248036544 13.220 99 25 48 0 1260073392 5831552 6205296
17:33:35 9992 8192000000 10164554224 10574585856 1276023664 13.477 100 10 20 0 1283470432 5251856 2194912
17:34:45 9218 8192000000 10179133280 10596880384 1293816912 13.639 100 11 22 0 1301319632 5017056 2485664
17:35:55 43270 8192000000 10197742976 10612727808 1303342464 13.726 100 27 54 2 1315827824 6076016 6409344
17:37:41 12077 8192000000 10198382192 10621177856 1311631120 13.801 100 21 42 1 1321888624 5607952 4649552
17:39:00 19968 8192000000 10239082016 10655092736 1340425280 14.061 100 48 95 0 1359174784 8135952 10613552
17:40:26 11045 8192000000 10274411008 10686869504 1364086176 14.274 100 51 102 2 1387629840 11734992 11808672
17:41:41 126408 8192000000 10283424928 10712064000 1362522176 14.260 99 31 61 2 1382982400 13150928 7309296
17:44:51 19871 8192000000 10292814688 10725969920 1379055008 14.408 100 24 48 3 1406379984 21300944 6024032
17:46:20 10282 8192000000 10318823504 10733211648 1390983664 14.515 100 61 121 1 1426819760 21894816 13941280
17:47:35 6606 8192000000 10329266912 10751856640 1398273920 14.580 100 56 112 0 1438602336 26553088 13775328
17:48:45 4114 8192000000 10350894336 10770059264 1408748480 14.673 100 74 148 1 1460430944 33393376 18289088
17:49:55 3689 8192000000 10355138672 10774761472 1404206368 14.632 99 64 125 1 1456865008 36111632 16547008
17:51:03 11460 8192000000 10329752208 10750943232 1384165040 14.454 99 53 106 1 1431935312 34316416 13453856
17:52:21 14898 8192000000 10320731632 10739589120 1365369840 14.286 99 53 102 1 1415944400 37433600 13140960
17:53:43 2082 8192000000 10335821264 10737590272 1359853376 14.236 98 84 168 3 1421491872 40356160 21282336
17:54:48 3143 8192000000 10343390976 10746093568 1380938896 14.425 100 81 157 1 1444380304 44152880 19288528
17:55:58 3753 8192000000 10350888304 10765467648 1392423424 14.527 100 67 133 1 1454940288 45083728 17433136
17:57:06 3672 8192000000 10358779296 10779869184 1406375584 14.652 100 61 121 1 1466285008 44025600 15883824
17:58:16 10161 8192000000 10339478848 10759458816 1383416016 14.447 100 47 94 0 1442681232 46491024 12774192
17:59:29 4328 8192000000 10352655824 10770907136 1385846000 14.469 100 55 110 1 1456659600 56852384 13961216
18:00:44 2992 8192000000 10336792064 10750484480 1360197168 14.239 99 62 125 1 1430544144 53383792 16963184
18:01:50 17965 8192000000 10342397072 10766856192 1375955344 14.380 100 32 64 3 1440445280 55160592 9329344
18:03:10 16905 8192000000 10331389232 10778796032 1381864544 14.433 100 30 60 2 1452546064 61403152 9278368
18:04:29 23932 8192000000 10330190448 10770325504 1381215168 14.427 100 24 47 0 1438111104 49756896 7139040
18:06:10 14584 8192000000 10324176656 10775654400 1377955168 14.398 100 22 44 0 1434592416 49976800 6660448
18:07:32 37499 8192000000 10259893264 10717708288 1323467312 13.908 98 8 16 0 1346798288 21381584 1949392
18:09:10 113638 8192000000 10288070304 10729455616 1364867008 14.281 99 39 77 2 1385908384 11124144 9917232
18:12:09 152972 8192000000 10320681232 10756632576 1353699008 14.181 97 24 47 0 1374309600 15974688 4635904
18:15:44 53622 8192000000 10308428448 10763542528 1393446048 14.537 100 11 22 2 1413194304 16944608 2803648
18:17:40 22669 8192000000 10303175216 10744885248 1369958224 14.327 98 44 85 2 1387777728 8218656 9600848
18:19:04 94417 8192000000 10304317472 10761461760 1393550896 14.538 100 23 46 0 1410115536 11729296 4835344
18:21:38 88129 8192000000 10299084976 10756128768 1385188576 14.463 99 12 24 1 1405316912 17521616 2606720
18:24:07 83683 8192000000 10305046736 10778845184 1406017392 14.649 100 22 44 1 1437571136 26349568 5204176
18:26:37 15097 8192000000 10312627632 10765217792 1405540416 14.644 100 37 74 2 1423909344 9994224 8374704
18:28:02 57312 8192000000 10308078064 10782654464 1405172704 14.641 100 8 16 2 1419850880 12962352 1715824
18:30:15 52685 8192000000 10306997488 10772529152 1393551072 14.538 99 14 27 0 1411297984 14632128 3114784
18:32:13 33422 8192000000 10300158112 10768293888 1381366960 14.429 98 19 38 1 1400559680 14814752 4377968
18:33:47 34483 8192000000 10304598864 10757963776 1383180048 14.445 99 32 64 0 1398703360 8109312 7414000
18:35:27 26119 8192000000 10297529600 10748080128 1390656656 14.512 100 22 44 1 1404303184 8534688 5111840
18:36:54 36903 8192000000 10268942576 10717274112 1319618368 13.873 96 20 40 1 1341617072 17394000 4604704
18:39:12 72264 8192000000 10281272688 10739630080 1348791424 14.137 98 16 32 1 1364423424 12254544 3377456
18:41:32 83721 8192000000 10288125536 10732732416 1319773408 13.875 96 14 28 1 1334118640 11909808 2435424
18:44:06 92106 8192000000 10279137168 10719150080 1330161344 13.969 97 16 32 1 1350425120 16512080 3751696
18:46:39 14062 8192000000 10277526176 10719563776 1363304512 14.267 100 45 89 0 1381455008 7920336 10230160
18:47:59 59039 8192000000 10244624080 10709671936 1340725504 14.064 99 28 56 1 1362503152 15167072 6610576
18:50:05 43954 8192000000 10239395600 10702446592 1330072384 13.968 99 8 16 1 1345605616 13796976 1736256
18:52:13 84124 8192000000 10242598256 10694819840 1304363024 13.735 96 14 29 1 1314525120 6721072 3441024
18:54:40 75202 8192000000 10264009248 10732539904 1350167232 14.149 99 5 10 0 1367045520 15921824 956464
18:56:55 60135 8192000000 10285187936 10745028608 1357941056 14.219 99 14 29 0 1386037408 24208112 3888240
18:59:46 39740 8192000000 10292764480 10746318848 1375164064 14.373 99 29 58 2 1389861856 7813024 6884768
19:01:26 45319 8192000000 10282462896 10728583168 1373646272 14.360 100 32 64 2 1395318848 15070944 6601632
19:03:13 81142 8192000000 10283391952 10743459840 1366277200 14.294 99 11 22 1 1384403392 15976144 2150048
19:05:39 66837 8192000000 10291293232 10742296576 1347626704 14.126 97 20 40 1 1367884448 15779328 4478416
19:07:46 70739 8192000000 10279559328 10724372480 1335879952 14.020 96 23 46 0 1350466992 9853696 4733344
19:10:02 91537 8192000000 10295191392 10750996480 1386847104 14.478 100 33 66 0 1402625408 7841104 7937200
19:12:35 28582 8192000000 10280798320 10746818560 1380774448 14.423 99 7 14 0 1391444416 9306800 1363168
19:14:07 59262 8192000000 10287656928 10749607936 1390806656 14.513 100 12 24 0 1400367680 7050192 2510832
19:17:06 68661 8192000000 10293872496 10751238144 1372889472 14.353 98 9 18 1 1385049680 10168064 1992144
19:19:21 50398 8192000000 10282076208 10731696128 1348495072 14.134 97 22 44 1 1365637824 11249072 5893680
19:21:14 49811 8192000000 10272129712 10725306368 1349226224 14.141 98 15 28 2 1367940976 15333888 3380864
19:23:04 59904 8192000000 10269543632 10715017216 1316512736 13.845 96 15 30 0 1329120112 9425504 3181872
19:25:09 23363 8192000000 10273012752 10717495296 1348952176 14.138 99 32 64 0 1366689632 10539600 7197856
19:26:38 72150 8192000000 10271404464 10719596544 1319448992 13.872 96 18 36 0 1338353504 14674704 4229808
19:28:52 32492 8192000000 10277083184 10737524736 1352525440 14.170 99 27 54 0 1378937024 20299936 6111648
19:30:30 107649 8192000000 10275729664 10729619456 1356728368 14.208 99 25 50 0 1372152064 9637600 5786096
19:33:18 91394 8192000000 10255659056 10723037184 1342393664 14.079 98 6 12 0 1355056096 11250176 1412256
19:35:51 82813 8192000000 10267054720 10717114368 1324528416 13.918 97 25 50 1 1337377120 6574864 6273840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-05-2022 19:39:54 to 13-05-2022 19:40:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 13-05-2022 19:40:10 to 13-05-2022 19:40:11.

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          244844
MON$OLDEST_ACTIVE               247184
MON$OLDEST_SNAPSHOT             247184
MON$NEXT_TRANSACTION            247184
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                       2033568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:259: 
:260: mscs-test-161.budzdorov.ru    Fri May 13 19:38:19 2022
:261:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:262: 
:263: 
:264: mscs-test-161.budzdorov.ru    Fri May 13 19:38:24 2022
:265:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:266: 
:267: 
:268: mscs-test-161.budzdorov.ru    Fri May 13 19:38:27 2022
:269:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:270: 
--- end of diff output ---

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

13.05.22 19:40:12. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-13 19:40:15 No severe PSQL-related problems occured
13.05.22 19:40:16. End of report.