Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 22: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 16101616 kB
MemAvailable 31083272 kB
Buffers 0 kB
Cached 15587888 kB
SwapCached 2528 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-12 22:38:20
DTS_END                         2022-07-12 22:38:20.9070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57220
TRN_ID                          239612
ATT_ID                          650
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: 9445.92 23229 1133511
customer order: creation 170.15 358 20418
customer order: refuse 14.43 386 1731
order to supplier: creation 46.00 1251 5520
order to supplier: removal 3.53 90189 423
invoice (draft): creation 45.86 3139 5503
invoice (draft): removal 56.32 684 6758
invoice accept: apply 41.57 17728 4988
invoice accept: cancel 50.09 745 6011
customer reserve: creation 3826.54 176 459185
customer reserve: removal 4892.43 51 587092
realization accept: apply 83.48 191 10018
realization accept: cancel 86.93 105 10431
payment from customer: creation 59.11 116 7093
payment from customer: removal 5.48 72 658
payment to supplier: creation 48.43 137 5811
payment to supplier: removal 3.46 71 415
service: total inventory turnovers 4.62 1213 554
service: total monetary turnovers 6.54 33 785
service: aggregate perf. data 0.94 17717 113
service: refresh index statistics 0.03 330225 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-07-2022 22:39:31 to 12-07-2022 22:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4646
TEST_TIME 2 5575
TEST_TIME 3 5850
TEST_TIME 4 4706
TEST_TIME 5 5298
TEST_TIME 6 5324
TEST_TIME 7 6294
TEST_TIME 8 6116
TEST_TIME 9 6212
TEST_TIME 10 6235
TEST_TIME 11 6243
TEST_TIME 12 6594
TEST_TIME 13 6831
TEST_TIME 14 6978
TEST_TIME 15 7110
TEST_TIME 16 7174
TEST_TIME 17 7747
TEST_TIME 18 7872
TEST_TIME 19 7955
TEST_TIME 20 8134
TEST_TIME 21 8426
TEST_TIME 22 9108
TEST_TIME 23 9370
TEST_TIME 24 9952
TEST_TIME 25 9870
TEST_TIME 26 10532
TEST_TIME 27 10768
TEST_TIME 28 10728
TEST_TIME 29 10694
TEST_TIME 30 10835
TEST_TIME 31 10630
TEST_TIME 32 10376
TEST_TIME 33 10323
TEST_TIME 34 10340
TEST_TIME 35 10191
TEST_TIME 36 10164
TEST_TIME 37 10080
TEST_TIME 38 9967
TEST_TIME 39 10044
TEST_TIME 40 9987
TEST_TIME 41 9825
TEST_TIME 42 9791
TEST_TIME 43 9914
TEST_TIME 44 9996
TEST_TIME 45 9915
TEST_TIME 46 10153
TEST_TIME 47 10052
TEST_TIME 48 10115
TEST_TIME 49 10070
TEST_TIME 50 10055
TEST_TIME 51 10229
TEST_TIME 52 10144
TEST_TIME 53 10215
TEST_TIME 54 10154
TEST_TIME 55 10279
TEST_TIME 56 10254
TEST_TIME 57 10131
TEST_TIME 58 10011
TEST_TIME 59 10063
TEST_TIME 60 10010
TEST_TIME 61 9887
TEST_TIME 62 9824
TEST_TIME 63 9986
TEST_TIME 64 9926
TEST_TIME 65 9904
TEST_TIME 66 9790
TEST_TIME 67 9738
TEST_TIME 68 9772
TEST_TIME 69 9755
TEST_TIME 70 9742
TEST_TIME 71 9806
TEST_TIME 72 9816
TEST_TIME 73 9797
TEST_TIME 74 9763
TEST_TIME 75 9820
TEST_TIME 76 9791
TEST_TIME 77 9827
TEST_TIME 78 9827
TEST_TIME 79 9794
TEST_TIME 80 9782
TEST_TIME 81 9850
TEST_TIME 82 9751
TEST_TIME 83 9680
TEST_TIME 84 9708
TEST_TIME 85 9675
TEST_TIME 86 9642
TEST_TIME 87 9562
TEST_TIME 88 9593
TEST_TIME 89 9542
TEST_TIME 90 9483
TEST_TIME 91 9507
TEST_TIME 92 9515
TEST_TIME 93 9514
TEST_TIME 94 9528
TEST_TIME 95 9561
TEST_TIME 96 9534
TEST_TIME 97 9547
TEST_TIME 98 9601
TEST_TIME 99 9628
TEST_TIME 100 9653
TEST_TIME 101 9665
TEST_TIME 102 9645
TEST_TIME 103 9639
TEST_TIME 104 9700
TEST_TIME 105 9757
TEST_TIME 106 9703
TEST_TIME 107 9644
TEST_TIME 108 9611
TEST_TIME 109 9577
TEST_TIME 110 9585
TEST_TIME 111 9574
TEST_TIME 112 9542
TEST_TIME 113 9509
TEST_TIME 114 9485
TEST_TIME 115 9462
TEST_TIME 116 9450
TEST_TIME 117 9423
TEST_TIME 118 9434
TEST_TIME 119 9517
TEST_TIME 120 9451

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 22:39:35 to 12-07-2022 22:39:38.

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 1560178 1560178 0 0.00 0 23637 6 0 0
doc_list_biud 1560178 1560178 0 0.00 0 64361 25 0 0
sp_add_invoice_to_stock 5110 4988 122 2.38 38 176569 17728 0 122
sp_cancel_adding_invoice 6011 6011 0 0.00 3 72987 745 0 0
sp_cancel_client_order 1731 1731 0 0.00 3 12164 386 0 0
sp_cancel_customer_reserve 587092 587092 0 0.00 0 64370 51 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 2 4948 72 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 8132 72 0 0
sp_cancel_supplier_invoice 6770 6758 12 0.17 1 17222 684 0 12
sp_cancel_supplier_order 423 423 0 0.00 8 1082979 90189 0 0
sp_cancel_write_off 10431 10431 0 0.00 1 29941 105 0 0
sp_client_order 20418 20418 0 0.00 3 63263 359 0 0
sp_customer_reserve 459185 459185 0 0.00 0 59588 177 0 0
sp_fill_shopping_cart 31439 31439 0 0.00 1 13648 36 0 0
sp_fill_shopping_cart_clo_res 459176 459176 0 0.00 0 15974 14 0 0
sp_get_clo_for_invoice 4977 4977 0 0.00 0 40101 300 0 0
sp_kill_cost_storno 17515 17515 0 0.00 0 8802 47 0 0
sp_kill_qstorno_ret_qs2qd 1191796 1191796 0 0.00 0 64347 28 0 0
sp_kill_qty_storno 627441 627441 0 0.00 0 64358 61 0 0
sp_lock_selected_doc 20716 20716 0 0.00 0 8741 6 0 0
sp_make_cost_storno 27899 27899 0 0.00 0 22929 57 0 0
sp_multiply_rows_for_pdistr 27899 27899 0 0.00 0 8580 30 0 0
sp_multiply_rows_for_qdistr 20418 20418 0 0.00 0 63253 170 0 0
sp_pay_from_customer 7093 7093 0 0.00 4 10958 117 0 0
sp_pay_to_supplier 5811 5811 0 0.00 4 22372 137 0 0
sp_payment_common 12904 12904 0 0.00 1 22365 36 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 12157 355 0 0
sp_qd_handle_on_invoice_upd_sts 10988 10988 0 0.00 0 27565 308 0 0
sp_qd_handle_on_reserve_upd_sts 10018 10018 0 0.00 0 16393 72 0 0
sp_reserve_write_off 10018 10018 0 0.00 6 16440 191 0 0
sp_supplier_invoice 5503 5503 0 0.00 6 87011 3139 0 0
sp_supplier_order 5520 5520 0 0.00 5 28080 1251 0 0
srv_aggregate_perf_data 113 113 0 0.00 2397 280667 17718 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 677 117919 43517 0 0
srv_find_qd_qs_mism 1086824 1086824 0 0.00 0 15797 5 0 0
srv_make_invnt_saldo 554 554 0 0.00 37 69792 1213 0 0
srv_make_money_saldo 785 785 0 0.00 0 9510 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 146265 542531 330226 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 26820 231 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 14912 113 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 7591 19 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 3456 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 2355 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 2438 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 554 11 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 21470 1455 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 1770 9 0 0
t$perf-norm:sp_client_order 20418 20418 0 0.00 0 8574 15 0 0
t$perf-norm:sp_customer_reserve 976 976 0 0.00 0 1180 5 0 0
t$perf-norm:sp_pay_from_customer 7093 7093 0 0.00 0 7886 9 0 0
t$perf-norm:sp_pay_to_supplier 5811 5811 0 0.00 0 15916 11 0 0
t$perf-norm:sp_reserve_write_off 10018 10018 0 0.00 0 8782 10 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 5810 16 0 0
t$perf-norm:sp_supplier_order 5520 5520 0 0.00 0 7485 16 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 245 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 174 3 0 0
t$perf-norm:srv_make_invnt_saldo 554 554 0 0.00 0 1300 28 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 3633 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 10198 9954 244 2.39 0 6907 17 0 244
v_all_customers 37354 37354 0 0.00 0 18 0 0 0
v_all_suppliers 30572 30572 0 0.00 0 22 1 0 0
v_all_wares 40832 40832 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 3682 9 0 0
v_cancel_client_order 3462 3462 0 0.00 0 3109 5 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 4924 12 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 1712 4 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 568 4 0 0
v_cancel_supplier_invoice 826 802 24 2.90 0 442 12 0 24
v_cancel_supplier_order 846 846 0 0.00 0 3183 13 0 0
v_cancel_write_off 1972 1972 0 0.00 0 3951 16 0 0
v_max_id_clo_ord 5520 5520 0 0.00 0 1381 2 0 0
v_max_id_clo_res 976 976 0 0.00 0 1934 12 0 0
v_max_id_ord_sup 5503 5503 0 0.00 0 5268 5 0 0
v_max_non_paid_invoice 1548 1548 0 0.00 0 1840 4 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 5015 5 0 0
v_min_id_clo_ord 5520 5520 0 0.00 0 5018 8 0 0
v_min_id_clo_res 976 976 0 0.00 0 6897 18 0 0
v_min_id_ord_sup 5503 5503 0 0.00 0 6983 10 0 0
v_min_non_paid_invoice 5811 5811 0 0.00 0 22361 31 0 0
v_min_non_paid_realizn 7093 7093 0 0.00 0 8593 27 0 0
v_our_firm 10318 10318 0 0.00 0 18 1 0 0
v_reserve_write_off 20034 20034 0 0.00 0 3383 4 0 0
x_lock_depdocs_on_canc_invoice 12368 12368 0 0.00 0 20284 105 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 14633 356 0 0
x_make_qstorno_1000_1200 5520 5520 0 0.00 1 28072 1178 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 3 84677 3009 0 0
x_make_qstorno_2100_3300 459185 459185 0 0.00 0 59588 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 22:39:39 to 12-07-2022 22:39:43.

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
20:08:24 2 8192000000 8800395776 8812199936 6683696 0.081 2 1 2 0 6785584 59104 42784
20:09:24 26214 8192000000 9918467328 10233303040 1001876448 10.897 100 90 180 0 1032088448 6418064 23793936
20:10:55 9193 8192000000 9951046304 10273079296 1036786240 11.234 100 94 188 1 1069515776 8922304 23807232
20:12:14 17607 8192000000 9977933616 10307162112 1054221424 11.401 100 86 172 0 1086262352 10354336 21686592
20:13:37 9229 8192000000 10027317456 10367152128 1101614160 11.853 100 84 168 0 1133287232 11527488 20145584
20:14:50 6567 8192000000 10084567056 10442366976 1157921920 12.384 100 76 152 0 1187632896 12139808 17571168
20:16:01 3414 8192000000 10117330768 10486669312 1207451136 12.845 100 79 156 0 1236183376 12164160 16568080
20:17:05 7695 8192000000 10155611280 10535731200 1250418768 13.242 100 75 150 0 1278382976 11598224 16365984
20:18:14 1764 8192000000 10192139104 10575319040 1280403984 13.517 100 86 170 0 1312927072 14065296 18457792
20:19:17 28607 8192000000 10192149776 10593304576 1297768240 13.675 100 51 99 0 1318318928 8607120 11943568
20:20:47 8618 8192000000 10192154688 10604392448 1303959216 13.731 100 23 46 0 1315867568 6942336 4966016
20:22:00 13646 8192000000 10189270592 10610925568 1310521312 13.791 100 25 50 2 1323285536 6267552 6496672
20:23:32 4442 8192000000 10202236640 10621693952 1314896656 13.830 100 24 48 0 1326367488 6078768 5392064
20:24:38 4432 8192000000 10214193040 10630836224 1318634368 13.864 100 23 46 0 1331210576 7057936 5518272
20:25:43 8200 8192000000 10210733408 10631462912 1323706880 13.910 100 17 32 0 1333196800 5847824 3642096
20:26:51 16973 8192000000 10229573776 10643468288 1327612992 13.946 100 68 138 1 1350964208 6876528 16474688
20:28:09 7823 8192000000 10201529120 10618953728 1300764016 13.702 99 22 44 0 1311850224 6043312 5042896
20:29:20 7463 8192000000 10176472336 10590801920 1277234480 13.488 99 24 48 1 1292094608 9434112 5426016
20:30:28 12589 8192000000 10148320352 10539438080 1233148080 13.083 97 19 38 0 1242913792 5444496 4321216
20:31:42 22951 8192000000 10111924848 10507362304 1118021696 12.008 89 20 40 0 1127593328 5152416 4419216
20:33:06 5984 8192000000 10130152928 10526990336 1231629376 13.069 99 31 62 0 1244263968 5754688 6879904
20:34:13 5075 8192000000 10157887008 10562703360 1269598944 13.418 100 27 52 0 1281824176 6686480 5538752
20:35:18 23184 8192000000 10177714096 10588925952 1291192160 13.615 100 30 62 2 1303677040 5315776 7169104
20:37:13 6631 8192000000 10185534816 10598993920 1293647568 13.637 100 24 48 0 1305299536 6564480 5087488
20:38:19 13577 8192000000 10224371568 10629574656 1320539328 13.882 100 32 63 0 1332943904 5261232 7143344
20:39:39 24947 8192000000 10254669552 10661310464 1338980640 14.048 100 51 101 0 1359444144 9425984 11037520
20:41:09 36584 8192000000 10273352192 10689875968 1362914592 14.264 100 40 80 1 1384080128 11302720 9862816
20:43:00 27606 8192000000 10273100880 10695704576 1367631632 14.306 100 30 58 0 1389216816 14769760 6815424
20:44:36 32247 8192000000 10291482032 10716471296 1371167616 14.338 100 42 84 0 1399655600 18456368 10031616
20:46:18 22791 8192000000 10317858192 10744152064 1395909680 14.559 100 48 94 0 1426821872 19387568 11524624
20:47:43 2450 8192000000 10334072912 10755571712 1407384656 14.661 100 66 132 2 1448257872 25428448 15444768
20:48:53 3299 8192000000 10327263328 10747301888 1399377440 14.589 100 58 109 0 1437666672 25358112 12931120
20:50:01 3014 8192000000 10337999008 10752249856 1397852272 14.576 100 71 136 0 1449208288 34834368 16521648
20:51:12 1465 8192000000 10354229152 10762625024 1402411152 14.616 100 87 165 1 1456389520 34335520 19642848
20:52:19 4977 8192000000 10336256112 10750050304 1390970768 14.515 100 71 140 0 1446454112 38544128 16939216
20:53:31 1999 8192000000 10343731824 10755207168 1391494704 14.519 100 69 138 0 1451930112 43029152 17406256
20:54:41 676 8192000000 10351200112 10753527808 1386063520 14.471 100 96 189 3 1453305664 43778352 23463792
20:55:48 3560 8192000000 10313968688 10726965248 1370743744 14.334 100 55 108 0 1422241984 37378688 14119552
20:56:56 15505 8192000000 10318497248 10741710848 1363582880 14.270 100 43 84 1 1416125008 40460608 12081520
20:58:21 3648 8192000000 10314298384 10733416448 1352272352 14.168 99 57 114 2 1415365776 47135312 15958112
20:59:37 7822 8192000000 10322900800 10740826112 1367931888 14.309 100 50 96 0 1427455072 47357440 12165744
21:00:48 8037 8192000000 10317662400 10751684608 1373884976 14.362 100 35 70 0 1423994816 40335680 9774160
21:01:57 26374 8192000000 10287778320 10722377728 1342249456 14.078 98 22 44 0 1391214880 42912192 6053232
21:03:25 40769 8192000000 10264832352 10702864384 1310260816 13.788 97 24 46 0 1348071664 30732096 7078752
21:05:10 14926 8192000000 10248020608 10692087808 1342669152 14.081 100 20 41 0 1358090048 10702592 4718304
21:06:30 12720 8192000000 10258178464 10689687552 1336088608 14.022 99 32 61 1 1354593456 11236656 7268192
21:07:56 70505 8192000000 10259714480 10700369920 1331146912 13.978 98 17 34 0 1348581376 13971776 3462688
21:10:07 66000 8192000000 10284320736 10717245440 1367409712 14.304 100 18 35 0 1381345184 10167680 3767792
21:12:13 18978 8192000000 10277415504 10727735296 1376709888 14.387 100 33 66 0 1395335136 10715264 7909984
21:13:43 13070 8192000000 10289370912 10722394112 1356312592 14.204 98 48 96 0 1375127520 8030608 10784320
21:15:00 23732 8192000000 10297677488 10735996928 1385975632 14.470 100 21 42 1 1401186064 10307616 4902816
21:16:25 16446 8192000000 10295787344 10739335168 1379616352 14.413 99 42 75 1 1406354848 18524832 8213664
21:17:43 96571 8192000000 10323908320 10778587136 1391912016 14.523 99 11 22 0 1410946368 16411984 2622368
21:20:24 84851 8192000000 10321842448 10784436224 1409414752 14.679 100 28 56 0 1441515568 25328384 6772432
21:22:49 37155 8192000000 10324793728 10778423296 1412438464 14.706 100 19 38 0 1428933728 11844064 4651200
21:24:26 63515 8192000000 10319333104 10783232000 1408094880 14.667 100 25 48 2 1425903632 12152832 5655920
21:26:31 44580 8192000000 10335038896 10796625920 1418163408 14.756 100 35 70 1 1440537248 14144912 8228928
21:28:15 51538 8192000000 10333531536 10790313984 1396211840 14.561 98 21 42 0 1417103552 15797680 5094032
21:30:10 114483 8192000000 10311272128 10771021824 1375101200 14.373 97 21 41 0 1394886848 15383264 4402384
21:33:05 98850 8192000000 10308949536 10769510400 1389311024 14.500 99 10 20 0 1404213856 12788240 2114592
21:35:44 96268 8192000000 10320572000 10769952768 1376763872 14.388 98 39 78 0 1398096832 12569088 8763872
21:38:20 13980 8192000000 10318496720 10764689408 1403434928 14.626 100 33 64 0 1420213440 9042400 7736112
21:39:35 17053 8192000000 10307671728 10752237568 1382772208 14.441 98 33 62 1 1397986000 7643728 7570064
21:40:53 77039 8192000000 10277932768 10753015808 1346133648 14.113 96 26 50 0 1375038288 21921872 6982768
21:43:11 55314 8192000000 10278592656 10755276800 1370366880 14.330 99 8 16 0 1387201232 15141088 1693264
21:45:08 37438 8192000000 10292476080 10753077248 1392047648 14.524 100 20 40 0 1407144016 9979600 5116768
21:46:49 63579 8192000000 10287995088 10740793344 1344526832 14.098 97 13 26 0 1355354384 8352624 2474928
21:48:57 66223 8192000000 10276930768 10730962944 1319119744 13.869 96 13 26 0 1335314336 13200448 2994144
21:51:13 74226 8192000000 10253669664 10704523264 1276061232 13.477 93 15 30 0 1291804784 12218640 3524912
21:53:28 29063 8192000000 10271060704 10712338432 1346373120 14.115 99 34 66 1 1366540432 12774000 7393312
21:54:59 12882 8192000000 10264571904 10697043968 1339710256 14.055 100 54 111 0 1364457200 12486960 12259984
21:56:14 32809 8192000000 10253671856 10697818112 1344381888 14.097 100 30 59 0 1366589696 15767936 6439872
21:57:52 15033 8192000000 10266143568 10702127104 1338223696 14.041 99 37 74 0 1356648192 10199328 8225168
21:59:14 67161 8192000000 10264463728 10715996160 1335507616 14.017 98 20 38 1 1351294544 10877280 4909648
22:02:07 62168 8192000000 10265047776 10726957056 1363836096 14.272 100 13 26 0 1380804576 14084400 2884080
22:04:29 68937 8192000000 10261426896 10721320960 1358721552 14.226 99 6 12 0 1371549232 11445232 1382448
22:06:38 34984 8192000000 10280620848 10741075968 1376154528 14.382 100 11 22 1 1392637904 13668992 2814384
22:08:21 64681 8192000000 10287576096 10755903488 1387333312 14.482 100 15 28 0 1402607264 12149808 3124144
22:10:26 65334 8192000000 10288656960 10732359680 1337451744 14.034 96 8 16 0 1348664160 9774640 1437776
22:12:32 91105 8192000000 10302672288 10761883648 1395065248 14.551 100 21 42 0 1408137584 8520160 4552176
22:15:04 42515 8192000000 10322951808 10778963968 1397196688 14.570 99 33 65 0 1420535648 15798992 7539968
22:16:54 55369 8192000000 10317258448 10776215552 1389471024 14.501 99 18 36 0 1408770720 15584560 3715136
22:18:51 87988 8192000000 10303944640 10757251072 1347114624 14.122 96 11 22 0 1365159104 15967312 2077168
22:21:23 53729 8192000000 10311620736 10784645120 1400789584 14.602 100 14 28 0 1422625312 18683472 3152256
22:23:17 66216 8192000000 10313023824 10778877952 1396985472 14.568 100 12 24 0 1415929744 16044704 2899568
22:25:23 117918 8192000000 10312841696 10773065728 1384074096 14.453 98 25 50 0 1401802672 11873328 5855248
22:28:21 80382 8192000000 10281725808 10753662976 1360737552 14.244 98 12 24 0 1374166208 10750592 2678064
22:30:43 70830 8192000000 10285768848 10742300672 1338763504 14.046 96 10 20 0 1351842992 10771344 2308144
22:33:51 80084 8192000000 10288589008 10758782976 1375671312 14.378 99 10 20 0 1389697776 11760256 2266208
22:36:19 62402 8192000000 10288654624 10747777024 1373817408 14.361 98 21 42 0 1396880816 18735984 4327424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-07-2022 22:39:45 to 12-07-2022 22:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 22:40:01 to 12-07-2022 22:40:03.

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          238853
MON$OLDEST_ACTIVE               239748
MON$OLDEST_SNAPSHOT             239748
MON$NEXT_TRANSACTION            239748
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                       2029920
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:972: 
:973: mscs-test-161.budzdorov.ru    Tue Jul 12 22:38:24 2022
:974:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:975: 
:976: 
:977: mscs-test-161.budzdorov.ru    Tue Jul 12 22:38:24 2022
:978:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:979: 
:980: 
:981: mscs-test-161.budzdorov.ru    Tue Jul 12 22:38:24 2022
:982:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:983: 
:984: 
:985: mscs-test-161.budzdorov.ru    Tue Jul 12 22:38:24 2022
:986:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:987: 
--- end of diff output ---

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

12.07.22 22:40:04. 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-07-12 22:40:06 No severe PSQL-related problems occured
12.07.22 22:40:07. End of report.