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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-14 19:38:34
DTS_END                         2023-01-14 19:38:34.6860
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/46648
TRN_ID                          244140
ATT_ID                          660
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: 9629.94 19328 1155593
customer order: creation 174.73 345 20968
customer order: refuse 14.86 349 1783
order to supplier: creation 46.88 1215 5625
order to supplier: removal 3.60 103856 432
invoice (draft): creation 46.17 3197 5540
invoice (draft): removal 55.62 793 6674
invoice accept: apply 41.54 16513 4985
invoice accept: cancel 50.70 742 6084
customer reserve: creation 3903.48 160 468418
customer reserve: removal 4998.21 59 599785
realization accept: apply 85.94 191 10313
realization accept: cancel 78.22 117 9386
payment from customer: creation 59.33 102 7119
payment from customer: removal 5.63 81 676
payment to supplier: creation 49.51 118 5941
payment to supplier: removal 3.68 70 441
service: total inventory turnovers 4.63 1086 555
service: total monetary turnovers 6.32 26 758
service: aggregate perf. data 0.88 19540 106
service: refresh index statistics 0.03 237996 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-01-2023 19:39:20 to 14-01-2023 19:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 974
TEST_TIME 2 1879
TEST_TIME 3 2763
TEST_TIME 4 3269
TEST_TIME 5 3673
TEST_TIME 6 3573
TEST_TIME 7 3641
TEST_TIME 8 4059
TEST_TIME 9 4439
TEST_TIME 10 4857
TEST_TIME 11 5259
TEST_TIME 12 5216
TEST_TIME 13 5388
TEST_TIME 14 5919
TEST_TIME 15 6229
TEST_TIME 16 6203
TEST_TIME 17 6562
TEST_TIME 18 7499
TEST_TIME 19 7622
TEST_TIME 20 7814
TEST_TIME 21 7982
TEST_TIME 22 8445
TEST_TIME 23 8486
TEST_TIME 24 8999
TEST_TIME 25 9303
TEST_TIME 26 9446
TEST_TIME 27 9432
TEST_TIME 28 9570
TEST_TIME 29 9982
TEST_TIME 30 10201
TEST_TIME 31 10801
TEST_TIME 32 10636
TEST_TIME 33 10620
TEST_TIME 34 10685
TEST_TIME 35 10617
TEST_TIME 36 10580
TEST_TIME 37 10578
TEST_TIME 38 10430
TEST_TIME 39 10218
TEST_TIME 40 10074
TEST_TIME 41 10135
TEST_TIME 42 10072
TEST_TIME 43 9970
TEST_TIME 44 9956
TEST_TIME 45 9863
TEST_TIME 46 9849
TEST_TIME 47 9890
TEST_TIME 48 10008
TEST_TIME 49 9899
TEST_TIME 50 10015
TEST_TIME 51 9871
TEST_TIME 52 9791
TEST_TIME 53 9737
TEST_TIME 54 9614
TEST_TIME 55 9516
TEST_TIME 56 9536
TEST_TIME 57 9544
TEST_TIME 58 9516
TEST_TIME 59 9498
TEST_TIME 60 9547
TEST_TIME 61 9583
TEST_TIME 62 9613
TEST_TIME 63 9733
TEST_TIME 64 9747
TEST_TIME 65 9810
TEST_TIME 66 9871
TEST_TIME 67 9834
TEST_TIME 68 9878
TEST_TIME 69 10032
TEST_TIME 70 10027
TEST_TIME 71 10072
TEST_TIME 72 9967
TEST_TIME 73 9889
TEST_TIME 74 9885
TEST_TIME 75 9899
TEST_TIME 76 9872
TEST_TIME 77 9831
TEST_TIME 78 9800
TEST_TIME 79 9764
TEST_TIME 80 9706
TEST_TIME 81 9772
TEST_TIME 82 9741
TEST_TIME 83 9696
TEST_TIME 84 9634
TEST_TIME 85 9621
TEST_TIME 86 9603
TEST_TIME 87 9548
TEST_TIME 88 9536
TEST_TIME 89 9516
TEST_TIME 90 9549
TEST_TIME 91 9536
TEST_TIME 92 9548
TEST_TIME 93 9596
TEST_TIME 94 9593
TEST_TIME 95 9702
TEST_TIME 96 9712
TEST_TIME 97 9736
TEST_TIME 98 9730
TEST_TIME 99 9831
TEST_TIME 100 9825
TEST_TIME 101 9814
TEST_TIME 102 9864
TEST_TIME 103 9793
TEST_TIME 104 9758
TEST_TIME 105 9722
TEST_TIME 106 9719
TEST_TIME 107 9697
TEST_TIME 108 9657
TEST_TIME 109 9645
TEST_TIME 110 9612
TEST_TIME 111 9566
TEST_TIME 112 9648
TEST_TIME 113 9617
TEST_TIME 114 9619
TEST_TIME 115 9630
TEST_TIME 116 9619
TEST_TIME 117 9651
TEST_TIME 118 9652
TEST_TIME 119 9667
TEST_TIME 120 9631

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-01-2023 19:39:24 to 14-01-2023 19:39:27.

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 1590103 1590103 0 0.00 0 21347 5 0 0
doc_list_biud 1590103 1590103 0 0.00 0 32292 28 0 0
sp_add_invoice_to_stock 5085 4985 100 1.96 19 169861 16514 0 100
sp_cancel_adding_invoice 6084 6084 0 0.00 5 69286 742 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 22561 349 0 0
sp_cancel_customer_reserve 599785 599785 0 0.00 0 25385 59 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 2 3728 82 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 4131 70 0 0
sp_cancel_supplier_invoice 6680 6674 6 0.08 2 16452 794 0 6
sp_cancel_supplier_order 432 432 0 0.00 7 1144998 103857 0 0
sp_cancel_write_off 9386 9386 0 0.00 1 12327 117 0 0
sp_client_order 20968 20968 0 0.00 3 26554 345 0 0
sp_customer_reserve 468418 468418 0 0.00 0 107406 161 0 0
sp_fill_shopping_cart 32115 32115 0 0.00 1 10862 31 0 0
sp_fill_shopping_cart_clo_res 468417 468417 0 0.00 0 18639 14 0 0
sp_get_clo_for_invoice 4983 4983 0 0.00 0 26583 273 0 0
sp_kill_cost_storno 16587 16587 0 0.00 0 12324 55 0 0
sp_kill_qstorno_ret_qs2qd 1216062 1216062 0 0.00 0 25356 32 0 0
sp_kill_qty_storno 639440 639440 0 0.00 0 32291 69 0 0
sp_lock_selected_doc 21225 21225 0 0.00 0 8716 6 0 0
sp_make_cost_storno 28356 28356 0 0.00 0 15676 54 0 0
sp_multiply_rows_for_pdistr 28356 28356 0 0.00 0 9650 26 0 0
sp_multiply_rows_for_qdistr 20968 20968 0 0.00 0 20041 159 0 0
sp_pay_from_customer 7119 7119 0 0.00 4 19760 103 0 0
sp_pay_to_supplier 5941 5941 0 0.00 5 12617 119 0 0
sp_payment_common 13060 13060 0 0.00 1 8282 25 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 22545 326 0 0
sp_qd_handle_on_invoice_upd_sts 11067 11067 0 0.00 0 32290 324 0 0
sp_qd_handle_on_reserve_upd_sts 10313 10313 0 0.00 0 32062 68 0 0
sp_reserve_write_off 10313 10313 0 0.00 6 32310 192 0 0
sp_supplier_invoice 5540 5540 0 0.00 6 89927 3198 0 0
sp_supplier_order 5625 5625 0 0.00 5 36842 1216 0 0
srv_aggregate_perf_data 106 106 0 0.00 3032 282083 19540 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1938 181087 45030 0 0
srv_find_qd_qs_mism 1107692 1107692 0 0.00 0 21345 4 0 0
srv_make_invnt_saldo 555 555 0 0.00 24 42053 1086 0 0
srv_make_money_saldo 758 758 0 0.00 0 2513 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 151545 317891 237997 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4985 4985 0 0.00 0 27072 183 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 6807 117 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 4840 12 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 6608 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 1708 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 2732 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 419 419 0 0.00 0 1083 14 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 19540 1544 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 4269 13 0 0
t$perf-norm:sp_client_order 20968 20968 0 0.00 0 11133 18 0 0
t$perf-norm:sp_customer_reserve 1022 1022 0 0.00 0 8579 17 0 0
t$perf-norm:sp_pay_from_customer 7119 7119 0 0.00 0 7949 12 0 0
t$perf-norm:sp_pay_to_supplier 5941 5941 0 0.00 0 10753 17 0 0
t$perf-norm:sp_reserve_write_off 10313 10313 0 0.00 0 7038 11 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 8636 17 0 0
t$perf-norm:sp_supplier_order 5625 5625 0 0.00 0 10914 22 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 261 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 864 45 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 723 19 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 524 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 91 25 0 0
v_add_invoice_to_stock 10166 9966 200 1.96 0 4226 17 0 200
v_all_customers 37976 37976 0 0.00 0 30 0 0 0
v_all_suppliers 30882 30882 0 0.00 0 29 1 0 0
v_all_wares 41936 41936 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1203 10 0 0
v_cancel_client_order 3566 3566 0 0.00 0 4395 7 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 3695 10 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 1257 5 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 657 4 0 0
v_cancel_supplier_invoice 850 838 12 1.41 0 4663 19 0 12
v_cancel_supplier_order 864 864 0 0.00 0 1156 5 0 0
v_cancel_write_off 2106 2106 0 0.00 0 2818 14 0 0
v_max_id_clo_ord 5625 5625 0 0.00 0 217 1 0 0
v_max_id_clo_res 1022 1022 0 0.00 0 13241 29 0 0
v_max_id_ord_sup 5521 5521 0 0.00 0 4872 4 0 0
v_max_non_paid_invoice 1646 1646 0 0.00 0 165 2 0 0
v_max_non_paid_realizn 3753 3753 0 0.00 0 3102 5 0 0
v_min_id_clo_ord 5625 5625 0 0.00 0 4239 8 0 0
v_min_id_clo_res 1022 1022 0 0.00 0 7382 20 0 0
v_min_id_ord_sup 5521 5521 0 0.00 0 2106 3 0 0
v_min_non_paid_invoice 5941 5941 0 0.00 0 5798 15 0 0
v_min_non_paid_realizn 7119 7119 0 0.00 0 8274 21 0 0
v_our_firm 10692 10692 0 0.00 0 18 1 0 0
v_reserve_write_off 20626 20626 0 0.00 0 12658 8 0 0
x_lock_depdocs_on_canc_invoice 12339 12339 0 0.00 0 17006 105 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 4906 328 0 0
x_make_qstorno_1000_1200 5625 5625 0 0.00 1 36836 1146 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 2 89906 3084 0 0
x_make_qstorno_2100_3300 468418 468418 0 0.00 0 107405 145 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-01-2023 19:39:28 to 14-01-2023 19:39:33.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:38 30591 8192000000 9898808160 10208628736 974675760 10.632 100 85 170 0 1004032640 6452848 22904032
17:11:15 21966 8192000000 9945018320 10267037696 1030048720 11.169 100 87 174 0 1060829056 8526064 22254272
17:12:49 11340 8192000000 9978127056 10311655424 1065692880 11.511 100 78 154 0 1094880704 9784416 19403408
17:14:06 5494 8192000000 10024045680 10369437696 1113779456 11.968 100 72 144 0 1140697136 9987440 16930240
17:15:24 4704 8192000000 10096011952 10457399296 1182267408 12.611 100 74 145 0 1209894000 11578784 16047808
17:16:30 3612 8192000000 10137699456 10507464704 1224631072 13.004 100 91 180 0 1258392640 13564752 20196816
17:17:37 2017 8192000000 10170754240 10548854784 1256789712 13.301 100 81 161 0 1287073520 12890688 17393120
17:18:42 1781 8192000000 10197498400 10580897792 1279263456 13.506 100 84 168 2 1311089984 13632080 18194448
17:19:45 27280 8192000000 10206685504 10598748160 1297353568 13.671 100 66 127 2 1321600832 10003104 14244160
17:21:13 4032 8192000000 10189349200 10601086976 1306906272 13.758 100 30 61 0 1320607600 6775312 6926016
17:22:18 11329 8192000000 10193467776 10609172480 1311910768 13.803 100 20 37 1 1322905728 6428368 4566592
17:23:32 55871 8192000000 10221506880 10633125888 1321730656 13.892 100 38 76 0 1336548944 6266064 8552224
17:25:31 4251 8192000000 10221545552 10638262272 1326146848 13.932 100 29 58 1 1339487600 5787808 7552944
17:26:38 4623 8192000000 10224586336 10640969728 1328868000 13.957 100 35 69 0 1344553920 8115520 7570400
17:27:44 11764 8192000000 10208252192 10614784000 1300503616 13.700 98 19 38 0 1310765536 6012992 4248928
17:28:58 22609 8192000000 10188198336 10607648768 1273682784 13.455 97 29 55 1 1286208448 5077536 7448128
17:30:23 4757 8192000000 10136450096 10534768640 1221733328 12.978 97 26 52 0 1238522128 10826272 5962528
17:31:33 9485 8192000000 10144194640 10547490816 1235646928 13.106 98 13 25 0 1243428176 4716416 3064832
17:32:46 10587 8192000000 10143420992 10542678016 1221684496 12.977 97 19 38 0 1230584864 4753376 4146992
17:34:00 39044 8192000000 10150027776 10558918656 1243502720 13.178 98 29 58 2 1256000752 5653856 6844176
17:35:39 9734 8192000000 10184594240 10597240832 1293513872 13.636 100 17 34 0 1303048016 5727168 3806976
17:36:51 7990 8192000000 10187987408 10607403008 1303503568 13.727 100 13 26 0 1312020816 5774464 2742784
17:38:00 20383 8192000000 10214274112 10629656576 1320063520 13.877 100 28 55 0 1331940688 4955792 6921376
17:39:22 57973 8192000000 10247131936 10661650432 1335724192 14.019 100 36 71 0 1349558064 5452752 8381120
17:41:25 22845 8192000000 10258302640 10679906304 1360518016 14.242 100 33 66 0 1376150032 7999312 7632704
17:42:50 22400 8192000000 10289334704 10711240704 1380306560 14.419 100 54 107 0 1403615104 11236288 12072256
17:44:30 10284 8192000000 10309650144 10731122688 1398117072 14.578 100 42 84 2 1421119184 13279088 9723024
17:45:52 7275 8192000000 10325172112 10746970112 1408330192 14.669 100 46 90 0 1439527456 19783072 11414192
17:47:07 1938 8192000000 10317599344 10739830784 1403409344 14.625 100 48 95 0 1436823456 21326240 12087872
17:48:12 4986 8192000000 10330919344 10751504384 1400708736 14.601 99 56 113 1 1439697632 24650672 14338224
17:49:19 2577 8192000000 10345918736 10770370560 1414710768 14.726 100 52 102 1 1455384608 26700736 13973104
17:50:29 6858 8192000000 10350557440 10769293312 1414916944 14.728 100 64 128 0 1459249808 28402960 15929904
17:51:44 2226 8192000000 10346067344 10763251712 1381724112 14.432 98 68 134 0 1433054576 34452672 16877792
17:52:52 2265 8192000000 10357395248 10773053440 1403923072 14.630 100 65 130 1 1461533680 41023728 16586880
17:54:00 5285 8192000000 10350686208 10767511552 1399614240 14.592 100 65 128 1 1453807488 37534352 16658896
17:55:21 4002 8192000000 10319544704 10729250816 1339827360 14.056 97 63 126 1 1395973984 39408080 16738544
17:56:37 4173 8192000000 10315128992 10733395968 1371974848 14.345 100 51 102 0 1423629568 38871760 12782960
17:57:51 6106 8192000000 10329074176 10743357440 1378568544 14.404 100 54 106 0 1433459200 41737632 13153024
17:59:05 11240 8192000000 10326311920 10755170304 1366548368 14.296 98 33 66 1 1419700656 43693552 9458736
18:00:25 21714 8192000000 10312919776 10743877632 1375753216 14.379 100 28 56 0 1422977296 39399232 7824848
18:01:50 7946 8192000000 10331310240 10751430656 1372472320 14.349 100 41 83 0 1429730032 45881008 11376704
18:03:06 17941 8192000000 10315219696 10742980608 1360587184 14.243 100 27 54 0 1415027808 46155872 8284752
18:04:40 56868 8192000000 10308964928 10754166784 1377872768 14.398 100 30 60 1 1422952928 36614960 8465200
18:06:59 46272 8192000000 10301868912 10754080768 1360138784 14.239 100 19 38 1 1409187504 43663568 5385152
18:08:55 58316 8192000000 10268060336 10731745280 1315123728 13.833 96 12 23 1 1354689152 36621088 2944336
18:10:55 49308 8192000000 10288411312 10730536960 1374927136 14.371 100 25 50 1 1390434448 9990176 5517136
18:12:45 81302 8192000000 10288581168 10738675712 1367915776 14.308 99 17 34 0 1386261296 13973264 4372256
18:15:19 162209 8192000000 10295647376 10738761728 1349003856 14.139 97 41 82 3 1368598432 9921152 9673424
18:19:09 181086 8192000000 10283654464 10730549248 1308050944 13.768 94 17 34 1 1322611056 10879248 3680864
18:23:11 26977 8192000000 10325882032 10773737472 1403718720 14.628 100 38 76 0 1426773600 13714256 9340624
18:24:50 30254 8192000000 10325508608 10770014208 1393433344 14.536 99 41 82 1 1420979984 17821120 9725520
18:26:24 56663 8192000000 10302786768 10768818176 1399326288 14.589 100 8 17 0 1419477920 17752528 2399104
18:28:24 66621 8192000000 10302986432 10772332544 1407067200 14.658 100 16 32 0 1417856944 6795696 3994048
18:30:38 36263 8192000000 10294075440 10764640256 1391923440 14.523 99 11 23 0 1401631936 7233408 2475088
18:32:23 67217 8192000000 10307615104 10774286336 1407478480 14.662 100 12 24 0 1420428624 9961408 2988736
18:34:38 56560 8192000000 10297794304 10769272832 1383930256 14.452 98 12 24 1 1400980064 14224656 2825152
18:36:48 13609 8192000000 10326943264 10782560256 1408858624 14.674 100 34 68 0 1429992976 14156624 6977728
18:38:03 61815 8192000000 10291213936 10759426048 1368063408 14.310 97 19 37 0 1381828032 9821632 3942992
18:40:09 77545 8192000000 10295798880 10754453504 1332051792 13.986 95 13 22 0 1348650928 14058688 2540448
18:42:28 38565 8192000000 10313173392 10771849216 1397595104 14.574 100 28 56 0 1413676784 9305680 6776000
18:44:25 77477 8192000000 10302703168 10763669504 1342513904 14.080 96 6 12 0 1358533456 14820912 1198640
18:46:54 88175 8192000000 10280548592 10749087744 1337257888 14.033 97 9 18 0 1363810496 24344912 2207696
18:49:52 116263 8192000000 10259358752 10713358336 1323199712 13.906 96 37 74 0 1339295456 7050304 9045440
18:52:49 39387 8192000000 10282728352 10730921984 1356002464 14.201 99 26 52 0 1373440304 11771824 5666016
18:54:29 18546 8192000000 10265872704 10708930560 1352401264 14.169 99 26 52 0 1366627616 8298416 5927936
18:55:55 72006 8192000000 10248326368 10700414976 1329836528 13.966 98 14 27 0 1344504272 11236080 3431664
18:58:14 40617 8192000000 10264187408 10709417984 1341006336 14.066 99 34 67 1 1366653328 18222208 7424784
18:59:59 53223 8192000000 10255010288 10703040512 1316148480 13.842 97 12 23 0 1325251280 6635792 2467008
19:01:54 61868 8192000000 10242896720 10693619712 1328500304 13.954 98 16 32 1 1345969680 14254944 3214432
19:04:32 57496 8192000000 10249726960 10713890816 1354155840 14.185 100 3 6 0 1365453168 10849456 447872
19:06:32 33185 8192000000 10277998400 10726387712 1356498624 14.206 99 28 56 0 1371317360 8685424 6133312
19:08:12 65479 8192000000 10281417008 10732589056 1336169712 14.023 97 19 39 0 1357475648 17229312 4076624
19:10:18 64402 8192000000 10274634736 10731782144 1340577424 14.063 97 18 36 0 1355492960 11173168 3742368
19:12:22 47210 8192000000 10291109136 10743414784 1364362448 14.277 99 23 46 1 1385157920 15493296 5302176
19:14:10 39304 8192000000 10290139920 10738839552 1364685232 14.279 99 16 32 0 1381712240 13477664 3549344
19:15:54 64218 8192000000 10308905248 10763771904 1377235552 14.392 99 26 51 0 1394609840 11416320 5957968
19:17:59 71849 8192000000 10310132656 10752483328 1378953728 14.407 99 22 44 0 1393764336 10396880 4413728
19:20:14 57352 8192000000 10282925200 10751160320 1368434384 14.313 99 20 40 0 1385466800 12267696 4764720
19:22:13 72220 8192000000 10284651632 10743726080 1386377872 14.474 100 19 38 0 1403645648 13208944 4058832
19:24:39 71158 8192000000 10290901744 10762903552 1383373696 14.447 99 7 15 0 1397033456 12093504 1566256
19:26:51 56676 8192000000 10287671568 10749345792 1370179968 14.329 98 12 24 0 1383399888 10440464 2779456
19:28:48 62787 8192000000 10277913296 10740076544 1326052432 13.931 95 12 25 0 1345108928 16056224 3000272
19:30:51 51334 8192000000 10292667792 10744406016 1371360928 14.339 99 21 42 0 1388730288 12843904 4525456
19:32:45 45636 8192000000 10269136656 10722742272 1339387600 14.052 97 22 44 0 1357464464 13211968 4864896
19:34:42 35636 8192000000 10272214928 10723590144 1306489056 13.754 95 28 56 0 1324984288 12115088 6380144
19:36:19 17267 8192000000 10281722512 10730958848 1370094080 14.328 100 40 80 0 1390897984 11342784 9461120
19:37:45 13568 8192000000 10250286400 10688253952 1331307136 13.979 98 30 60 1 1348033760 10156416 6570208

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-01-2023 19:39:35 to 14-01-2023 19:39:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-01-2023 19:39:51 to 14-01-2023 19:39:53.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240964
MON$OLDEST_ACTIVE               244268
MON$OLDEST_SNAPSHOT             244268
MON$NEXT_TRANSACTION            244268
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                       2041888
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.33653 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33653 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33653 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33653 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 ---
:207: 
:208: mscs-test-161.budzdorov.ru    Sat Jan 14 19:38:36 2023
:209:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:210: 
:211: 
:212: mscs-test-161.budzdorov.ru    Sat Jan 14 19:38:38 2023
:213:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:214: 
:215: 
:216: mscs-test-161.budzdorov.ru    Sat Jan 14 19:38:38 2023
:217:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:218: 
:219: 
:220: mscs-test-161.budzdorov.ru    Sat Jan 14 19:38:38 2023
:221:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:222: 
:223: 
:224: mscs-test-161.budzdorov.ru    Sat Jan 14 19:38:40 2023
:225:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:226: 
--- end of diff output ---

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

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