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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-17 22:37:55
DTS_END                         2021-11-17 22:37:55.2970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53204
TRN_ID                          236591
ATT_ID                          659
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: 9046.03 25556 1085523
customer order: creation 168.84 393 20261
customer order: refuse 14.44 338 1733
order to supplier: creation 45.25 1363 5430
order to supplier: removal 3.48 95593 418
invoice (draft): creation 45.65 3603 5478
invoice (draft): removal 51.13 812 6135
invoice accept: apply 40.87 18260 4904
invoice accept: cancel 45.97 831 5516
customer reserve: creation 3810.52 179 457262
customer reserve: removal 4526.85 58 543222
realization accept: apply 82.84 185 9941
realization accept: cancel 81.44 113 9773
payment from customer: creation 58.37 107 7004
payment from customer: removal 6.08 82 729
payment to supplier: creation 48.63 147 5836
payment to supplier: removal 3.36 82 403
service: total inventory turnovers 4.82 930 578
service: total monetary turnovers 6.70 21 804
service: aggregate perf. data 0.78 24540 93
service: refresh index statistics 0.03 363478 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-11-2021 22:38:28 to 17-11-2021 22:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1398
TEST_TIME 2 3680
TEST_TIME 3 4023
TEST_TIME 4 4166
TEST_TIME 5 3661
TEST_TIME 6 3422
TEST_TIME 7 3678
TEST_TIME 8 4069
TEST_TIME 9 4597
TEST_TIME 10 4964
TEST_TIME 11 5065
TEST_TIME 12 5304
TEST_TIME 13 5680
TEST_TIME 14 5894
TEST_TIME 15 6366
TEST_TIME 16 6875
TEST_TIME 17 7169
TEST_TIME 18 7863
TEST_TIME 19 7884
TEST_TIME 20 8161
TEST_TIME 21 8645
TEST_TIME 22 8734
TEST_TIME 23 9044
TEST_TIME 24 9485
TEST_TIME 25 9480
TEST_TIME 26 9765
TEST_TIME 27 10548
TEST_TIME 28 10221
TEST_TIME 29 10279
TEST_TIME 30 10445
TEST_TIME 31 10441
TEST_TIME 32 10386
TEST_TIME 33 10215
TEST_TIME 34 10171
TEST_TIME 35 9973
TEST_TIME 36 9845
TEST_TIME 37 9898
TEST_TIME 38 9990
TEST_TIME 39 9798
TEST_TIME 40 9686
TEST_TIME 41 9581
TEST_TIME 42 9694
TEST_TIME 43 9625
TEST_TIME 44 9502
TEST_TIME 45 9377
TEST_TIME 46 9305
TEST_TIME 47 9246
TEST_TIME 48 9153
TEST_TIME 49 9160
TEST_TIME 50 9230
TEST_TIME 51 9179
TEST_TIME 52 9114
TEST_TIME 53 9105
TEST_TIME 54 9017
TEST_TIME 55 8938
TEST_TIME 56 8862
TEST_TIME 57 8848
TEST_TIME 58 8847
TEST_TIME 59 8799
TEST_TIME 60 8906
TEST_TIME 61 8900
TEST_TIME 62 8973
TEST_TIME 63 8966
TEST_TIME 64 9118
TEST_TIME 65 9099
TEST_TIME 66 9108
TEST_TIME 67 9211
TEST_TIME 68 9180
TEST_TIME 69 9169
TEST_TIME 70 9247
TEST_TIME 71 9307
TEST_TIME 72 9384
TEST_TIME 73 9261
TEST_TIME 74 9165
TEST_TIME 75 9202
TEST_TIME 76 9121
TEST_TIME 77 9088
TEST_TIME 78 9040
TEST_TIME 79 9004
TEST_TIME 80 8926
TEST_TIME 81 8924
TEST_TIME 82 8913
TEST_TIME 83 8875
TEST_TIME 84 8922
TEST_TIME 85 8914
TEST_TIME 86 8892
TEST_TIME 87 8905
TEST_TIME 88 9008
TEST_TIME 89 9060
TEST_TIME 90 9093
TEST_TIME 91 9091
TEST_TIME 92 9150
TEST_TIME 93 9134
TEST_TIME 94 9171
TEST_TIME 95 9131
TEST_TIME 96 9156
TEST_TIME 97 9122
TEST_TIME 98 9043
TEST_TIME 99 9089
TEST_TIME 100 9065
TEST_TIME 101 9040
TEST_TIME 102 9039
TEST_TIME 103 9023
TEST_TIME 104 8990
TEST_TIME 105 8969
TEST_TIME 106 8939
TEST_TIME 107 8923
TEST_TIME 108 8942
TEST_TIME 109 8922
TEST_TIME 110 8918
TEST_TIME 111 8941
TEST_TIME 112 8993
TEST_TIME 113 8976
TEST_TIME 114 9001
TEST_TIME 115 8998
TEST_TIME 116 8991
TEST_TIME 117 8997
TEST_TIME 118 9031
TEST_TIME 119 9080
TEST_TIME 120 9050

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-11-2021 22:38:31 to 17-11-2021 22:38:35.

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 1508002 1508002 0 0.00 0 21788 6 0 0
doc_list_biud 1508001 1508001 0 0.00 0 70489 27 0 0
sp_add_invoice_to_stock 4989 4904 85 1.70 36 151157 18260 0 85
sp_cancel_adding_invoice 5516 5516 0 0.00 5 83617 832 0 0
sp_cancel_client_order 1733 1733 0 0.00 4 11361 338 0 0
sp_cancel_customer_reserve 543222 543222 0 0.00 1 33149 58 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 7176 82 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 6588 82 0 0
sp_cancel_supplier_invoice 6145 6135 10 0.16 2 70509 812 0 10
sp_cancel_supplier_order 418 418 0 0.00 6 939708 95593 0 0
sp_cancel_write_off 9773 9773 0 0.00 1 9576 114 0 0
sp_client_order 20261 20261 0 0.00 4 15568 393 0 0
sp_customer_reserve 457262 457262 0 0.00 0 42896 180 0 0
sp_fill_shopping_cart 31151 31151 0 0.00 1 23280 41 0 0
sp_fill_shopping_cart_clo_res 457249 457249 0 0.00 0 15020 14 0 0
sp_get_clo_for_invoice 4891 4891 0 0.00 0 22465 330 0 0
sp_kill_cost_storno 16421 16421 0 0.00 0 8533 58 0 0
sp_kill_qstorno_ret_qs2qd 1102770 1102770 0 0.00 0 70481 32 0 0
sp_kill_qty_storno 581628 581628 0 0.00 0 70485 69 0 0
sp_lock_selected_doc 20595 20595 0 0.00 0 6583 4 0 0
sp_make_cost_storno 27672 27672 0 0.00 0 21711 62 0 0
sp_multiply_rows_for_pdistr 27672 27672 0 0.00 0 11100 29 0 0
sp_multiply_rows_for_qdistr 20261 20261 0 0.00 0 12309 187 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 15036 108 0 0
sp_pay_to_supplier 5836 5836 0 0.00 5 15110 148 0 0
sp_payment_common 12840 12840 0 0.00 1 11020 35 0 0
sp_qd_handle_on_cancel_clo 1733 1733 0 0.00 0 11351 305 0 0
sp_qd_handle_on_invoice_upd_sts 10407 10407 0 0.00 0 28937 349 0 0
sp_qd_handle_on_reserve_upd_sts 9940 9940 0 0.00 0 13945 63 0 0
sp_reserve_write_off 9941 9941 0 0.00 6 15059 186 0 0
sp_supplier_invoice 5478 5478 0 0.00 7 92414 3604 0 0
sp_supplier_order 5430 5430 0 0.00 5 68816 1363 0 0
srv_aggregate_perf_data 93 93 0 0.00 2364 256907 24540 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1193 134159 47798 0 0
srv_find_qd_qs_mism 1037842 1037842 0 0.00 0 19218 5 0 0
srv_make_invnt_saldo 578 578 0 0.00 31 65879 931 0 0
srv_make_money_saldo 804 804 0 0.00 1 492 21 0 0
srv_recalc_idx_stat 3 3 0 0.00 200976 554981 363478 0 0
t$perf-abend:sp_add_invoice_to_stock 85 85 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 7937 167 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 0 9689 122 0 0
t$perf-norm:sp_cancel_client_order 1733 1733 0 0.00 0 5318 20 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 3132 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 1950 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 2953 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 1901 16 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 21991 1483 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1046 4 0 0
t$perf-norm:sp_client_order 20261 20261 0 0.00 0 11642 20 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 2107 14 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 9703 13 0 0
t$perf-norm:sp_pay_to_supplier 5836 5836 0 0.00 0 11740 13 0 0
t$perf-norm:sp_reserve_write_off 9941 9941 0 0.00 0 8491 17 0 0
t$perf-norm:sp_supplier_invoice 5478 5478 0 0.00 0 10115 18 0 0
t$perf-norm:sp_supplier_order 5430 5430 0 0.00 0 10261 17 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 220 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 578 578 0 0.00 0 1819 26 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 2716 18 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9948 9778 170 1.70 0 7595 17 0 170
v_all_customers 36680 36680 0 0.00 0 14 1 0 0
v_all_suppliers 30366 30366 0 0.00 0 22 1 0 0
v_all_wares 40522 40522 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 4348 12 0 0
v_cancel_client_order 3466 3466 0 0.00 0 3736 6 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 1771 6 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 3065 7 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 292 3 0 0
v_cancel_supplier_invoice 810 790 20 2.46 0 266 10 0 20
v_cancel_supplier_order 836 836 0 0.00 0 1735 4 0 0
v_cancel_write_off 2006 2006 0 0.00 0 2435 13 0 0
v_max_id_clo_ord 5423 5423 0 0.00 0 557 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 4801 21 0 0
v_max_id_ord_sup 5466 5466 0 0.00 0 4599 4 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 550 3 0 0
v_max_non_paid_realizn 3760 3760 0 0.00 0 4646 4 0 0
v_min_id_clo_ord 5423 5423 0 0.00 0 8086 10 0 0
v_min_id_clo_res 979 979 0 0.00 0 3281 6 0 0
v_min_id_ord_sup 5466 5466 0 0.00 0 5424 8 0 0
v_min_non_paid_invoice 5836 5836 0 0.00 0 11012 31 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 10199 23 0 0
v_our_firm 10330 10330 0 0.00 0 18 1 0 0
v_reserve_write_off 19880 19880 0 0.00 0 6254 6 0 0
x_lock_depdocs_on_canc_invoice 11256 11256 0 0.00 0 17528 117 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 17201 371 0 0
x_make_qstorno_1000_1200 5430 5430 0 0.00 1 68801 1280 0 0
x_make_qstorno_1200_2000 5478 5478 0 0.00 4 86664 3455 0 0
x_make_qstorno_2100_3300 457262 457262 0 0.00 0 42889 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-11-2021 22:38:36 to 17-11-2021 22:38:40.

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:07:59 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 30752 8192000000 9905026976 10217066496 982157904 10.705 100 89 179 1 1011517776 6010400 23349472
20:10:38 25436 8192000000 9943325232 10267049984 1024111072 11.112 100 85 170 0 1054324576 7918416 22295088
20:12:06 26797 8192000000 9991020416 10329071616 1068379648 11.537 100 85 170 0 1098961824 9405520 21176656
20:13:37 11762 8192000000 10053322304 10408595456 1142953312 12.243 100 75 150 3 1171997584 11208592 17835680
20:14:52 6585 8192000000 10092243360 10456412160 1181298032 12.602 100 82 164 1 1212435856 12366704 18771120
20:16:01 6074 8192000000 10119050496 10495885312 1219910704 12.961 100 75 148 0 1248613584 12569472 16133408
20:17:10 3183 8192000000 10164009040 10544386048 1248360624 13.223 100 77 154 1 1276926864 11258336 17307904
20:18:14 2823 8192000000 10191414768 10576318464 1283350784 13.544 100 90 179 0 1316361936 13284704 19726448
20:19:19 2831 8192000000 10219292912 10603630592 1295993296 13.659 100 96 189 3 1330378048 13003904 21380848
20:20:24 2533 8192000000 10215210176 10605776896 1307743200 13.766 100 65 130 0 1333538608 11337904 14457504
20:21:27 6996 8192000000 10205973536 10616897536 1316544608 13.845 100 30 60 1 1330761312 6725408 7491296
20:22:36 4510 8192000000 10211523680 10625466368 1323307120 13.907 100 20 40 0 1334217504 6349632 4560752
20:23:43 11267 8192000000 10205581280 10630287360 1327666368 13.946 100 21 41 1 1337837584 5898976 4272240
20:24:56 74062 8192000000 10225190144 10650329088 1334875984 14.011 100 31 62 0 1348102928 5802800 7424144
20:27:16 6333 8192000000 10229208384 10648580096 1322438784 13.899 99 29 57 1 1334856656 5584224 6833648
20:28:23 5022 8192000000 10177729344 10586963968 1258780416 13.319 96 21 41 0 1269065200 5349488 4935296
20:29:32 12124 8192000000 10168917072 10581819392 1252190800 13.258 97 8 14 1 1258482944 4904016 1388128
20:30:45 51211 8192000000 10130194496 10530050048 1154525776 12.352 91 29 58 2 1166979376 5654464 6799136
20:32:38 6500 8192000000 10149625152 10548371456 1208088912 12.851 96 22 44 1 1222960144 10010832 4860400
20:33:47 9671 8192000000 10175572368 10577805312 1272524672 13.445 100 17 34 0 1281889568 5139392 4225504
20:34:57 10073 8192000000 10190992336 10598682624 1289885872 13.603 100 20 39 1 1300789808 5671088 5232848
20:36:09 22935 8192000000 10188153008 10601930752 1296392368 13.662 100 29 58 0 1309181104 6273280 6515456
20:37:39 7929 8192000000 10212793696 10627731456 1320137456 13.878 100 32 64 2 1333797600 6365232 7294912
20:39:02 7930 8192000000 10242186640 10649059328 1333901392 14.002 100 49 94 4 1355503248 10797200 10804656
20:40:15 25438 8192000000 10269359152 10686570496 1356245584 14.204 100 42 84 2 1381948256 16125328 9577344
20:41:43 35791 8192000000 10280921616 10704830464 1373163648 14.355 100 31 62 1 1397078736 16185840 7729248
20:43:26 35509 8192000000 10309797920 10727931904 1384612816 14.458 100 53 103 0 1417272192 20822176 11837200
20:45:17 24905 8192000000 10303082736 10731470848 1388719280 14.494 100 41 82 1 1420791616 21269456 10802880
20:46:50 17394 8192000000 10312569808 10738794496 1392974768 14.532 100 47 94 1 1426423584 21801712 11647104
20:48:12 2130 8192000000 10345788240 10764521472 1410117216 14.685 100 68 135 1 1452815600 27037664 15660720
20:49:17 8064 8192000000 10360046032 10779901952 1420945488 14.781 100 70 139 0 1469592816 32234864 16412464
20:50:32 3380 8192000000 10358818112 10774032384 1411322704 14.696 100 75 148 2 1463749488 32955088 19471696
20:51:43 1192 8192000000 10363385712 10773602304 1400971648 14.604 100 83 165 2 1461838912 40724288 20142976
20:52:46 2294 8192000000 10355446592 10764451840 1395595088 14.556 100 86 169 2 1455697904 39412624 20690192
20:53:55 10505 8192000000 10339709504 10763689984 1391617712 14.520 99 48 96 3 1441386864 37548640 12220512
20:55:11 6933 8192000000 10336025728 10765549568 1399865360 14.594 100 51 102 0 1449604304 36693168 13045776
20:56:22 14020 8192000000 10333270416 10754879488 1384117840 14.453 100 48 95 2 1426787136 30240896 12428400
20:57:41 18546 8192000000 10313921824 10738585600 1363207184 14.266 99 33 66 0 1405066464 33150384 8708896
20:59:03 8117 8192000000 10278726784 10698416128 1341500256 14.071 99 43 84 1 1388762768 36046304 11216208
21:00:15 9240 8192000000 10294919472 10727161856 1357218560 14.212 100 30 59 0 1401179008 35486640 8473808
21:01:36 18422 8192000000 10305427088 10734833664 1377590192 14.395 100 26 52 1 1410229520 24830240 7809088
21:03:03 23268 8192000000 10302621360 10734637056 1353834912 14.182 99 33 66 1 1400178192 36681568 9661712
21:04:28 25283 8192000000 10298137440 10751631360 1353843856 14.182 99 22 44 1 1397702976 37559488 6299632
21:05:54 47035 8192000000 10266814880 10708066304 1322101568 13.896 98 41 81 2 1357790928 24842096 10847264
21:07:43 30906 8192000000 10257415920 10711670784 1330353056 13.970 99 15 30 0 1355587616 21982272 3252288
21:10:05 35337 8192000000 10265803984 10703228928 1335519248 14.017 99 10 20 0 1352673648 15041808 2112592
21:11:56 47635 8192000000 10273353552 10707046400 1358098944 14.220 100 45 89 2 1377351136 8830064 10422128
21:13:47 134159 8192000000 10267773520 10726834176 1344692768 14.100 98 13 27 0 1366301312 18242704 3365840
21:17:03 33805 8192000000 10279671952 10726744064 1376569632 14.386 100 48 96 0 1397329872 9428400 11331840
21:18:43 34283 8192000000 10300520320 10738843648 1383777120 14.450 100 40 81 1 1408575168 14815600 9982448
21:20:40 28677 8192000000 10289909360 10744721408 1388549984 14.493 100 28 56 1 1405850912 10109008 7191920
21:22:14 69108 8192000000 10288887568 10751954944 1381667376 14.431 99 9 18 1 1394614256 10914368 2032512
21:24:26 100671 8192000000 10299692336 10753282048 1383701760 14.450 99 22 45 0 1405687680 16504576 5481344
21:27:07 75642 8192000000 10276440432 10748215296 1373072864 14.355 99 19 36 0 1390731984 13121456 4537664
21:29:26 44459 8192000000 10288488960 10755969024 1399523136 14.591 100 35 70 1 1416748992 9440496 7785360
21:31:12 69938 8192000000 10281734848 10749227008 1386285120 14.473 99 14 28 0 1396352128 6734096 3332912
21:33:25 81105 8192000000 10280713808 10752466944 1361504880 14.251 97 6 12 0 1370692160 7689408 1497872
21:35:46 51149 8192000000 10298951104 10757574656 1390343968 14.509 99 21 42 1 1407425056 12092160 4988928
21:37:57 49801 8192000000 10301677632 10758373376 1397328016 14.571 100 20 40 0 1410892096 9317728 4246352
21:39:54 116123 8192000000 10282175952 10743705600 1313996272 13.822 94 16 32 0 1329572240 11959344 3616624
21:42:51 24774 8192000000 10279955264 10723491840 1340656528 14.063 97 40 80 1 1360841936 10934512 9250896
21:44:25 91431 8192000000 10294053904 10732085248 1341280352 14.069 97 17 34 0 1356604768 11387184 3937232
21:47:02 63039 8192000000 10256533712 10707054592 1300121008 13.696 95 30 60 1 1320865520 14362192 6382320
21:49:12 95710 8192000000 10257383232 10705268736 1323772672 13.911 97 7 14 0 1348629760 23255776 1601312
21:51:50 20514 8192000000 10249548544 10693173248 1340196096 14.059 99 23 46 1 1361169328 15846720 5126512
21:53:12 59273 8192000000 10237685680 10696503296 1317052144 13.850 97 8 16 0 1334277680 15399008 1826528
21:55:16 48437 8192000000 10226597136 10671386624 1293402624 13.635 96 26 54 1 1313949328 14432112 6114592
21:57:15 28620 8192000000 10244858176 10694778880 1330660128 13.973 98 10 20 0 1341840160 9059104 2120928
21:58:44 67140 8192000000 10236292960 10689142784 1308227616 13.770 97 13 27 0 1326436016 14967648 3240752
22:00:55 69685 8192000000 10254205296 10705223680 1332987600 13.994 98 16 32 0 1354324992 16845520 4491872
22:03:10 35779 8192000000 10296836496 10740547584 1380114128 14.418 100 35 70 0 1404883072 16578480 8190464
22:04:47 99791 8192000000 10284474256 10748743680 1365223584 14.284 99 20 40 0 1386196704 15682144 5290976
22:07:26 67949 8192000000 10305446720 10744606720 1381032672 14.426 99 20 40 1 1396176288 11164480 3979136
22:09:36 88559 8192000000 10322117456 10762072064 1382577040 14.440 99 18 36 0 1402694432 16399216 3718176
22:12:06 122442 8192000000 10307070432 10762285056 1344779488 14.100 96 20 40 1 1370436816 21218992 4438336
22:15:09 74147 8192000000 10310906640 10768318464 1399444896 14.590 100 35 70 2 1418467888 10463920 8559072
22:17:31 61698 8192000000 10286483440 10747006976 1363176784 14.266 97 10 18 1 1373884480 8362976 2344720
22:19:35 77398 8192000000 10284137264 10746466304 1370596496 14.332 98 9 19 2 1382812592 10228752 1987344
22:22:02 77211 8192000000 10281186592 10746130432 1361138128 14.248 97 20 40 1 1377640640 12046480 4456032
22:24:31 64515 8192000000 10279653056 10748579840 1366605248 14.297 98 16 31 0 1379240640 9048320 3587072
22:26:42 57729 8192000000 10291410368 10750500864 1360154240 14.239 97 23 46 3 1375914048 10230576 5529232
22:28:45 71398 8192000000 10294188304 10757689344 1389594832 14.502 100 15 30 1 1406659696 13578112 3486752
22:31:00 39984 8192000000 10307670384 10763325440 1393850048 14.540 100 31 62 0 1414126256 12843760 7432448
22:32:56 39103 8192000000 10262314688 10706620416 1288457744 13.590 94 15 30 1 1299614400 7810096 3346560
22:34:45 82975 8192000000 10263122032 10696015872 1278600512 13.500 95 27 54 2 1303845648 19183024 6062112
22:37:09 33706 8192000000 10267023152 10703527936 1339430864 14.052 99 16 30 2 1350171104 7649824 3090416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-11-2021 22:38:42 to 17-11-2021 22:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 85 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-11-2021 22:38:57 to 17-11-2021 22:38:59.

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          234595
MON$OLDEST_ACTIVE               236706
MON$OLDEST_SNAPSHOT             236706
MON$NEXT_TRANSACTION            236706
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                       2012352
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33535 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33535 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33535 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33535 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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Nov 17 22:37:56 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:35: 
--- end of diff output ---

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

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