Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.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 16271936 kB
MemAvailable 31312760 kB
Buffers 0 kB
Cached 15355140 kB
SwapCached 440 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.9
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-12-29 22:37:57
DTS_END                         2021-12-29 22:37:57.3260
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33030
TRN_ID                          246779
ATT_ID                          653
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: 9661.32 20634 1159358
customer order: creation 179.67 312 21560
customer order: refuse 15.46 283 1855
order to supplier: creation 46.88 1282 5625
order to supplier: removal 3.61 96737 433
invoice (draft): creation 47.62 3398 5714
invoice (draft): removal 55.70 753 6684
invoice accept: apply 44.23 17575 5307
invoice accept: cancel 50.73 734 6088
customer reserve: creation 4007.94 178 480953
customer reserve: removal 4902.96 55 588355
realization accept: apply 86.41 174 10369
realization accept: cancel 86.78 110 10414
payment from customer: creation 61.06 122 7327
payment from customer: removal 6.06 62 727
payment to supplier: creation 49.89 125 5987
payment to supplier: removal 3.60 73 432
service: total inventory turnovers 4.95 990 594
service: total monetary turnovers 6.73 51 807
service: aggregate perf. data 1.03 14124 123
service: refresh index statistics 0.03 275540 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 29-12-2021 22:38:35 to 29-12-2021 22:38:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2263
TEST_TIME 2 3123
TEST_TIME 3 3737
TEST_TIME 4 3208
TEST_TIME 5 3701
TEST_TIME 6 4375
TEST_TIME 7 4671
TEST_TIME 8 4644
TEST_TIME 9 4747
TEST_TIME 10 4953
TEST_TIME 11 5598
TEST_TIME 12 5831
TEST_TIME 13 6526
TEST_TIME 14 6661
TEST_TIME 15 6797
TEST_TIME 16 6893
TEST_TIME 17 7107
TEST_TIME 18 7294
TEST_TIME 19 7328
TEST_TIME 20 7839
TEST_TIME 21 7981
TEST_TIME 22 8101
TEST_TIME 23 8335
TEST_TIME 24 8263
TEST_TIME 25 8200
TEST_TIME 26 9071
TEST_TIME 27 9512
TEST_TIME 28 9561
TEST_TIME 29 9613
TEST_TIME 30 10286
TEST_TIME 31 10894
TEST_TIME 32 11008
TEST_TIME 33 10892
TEST_TIME 34 10864
TEST_TIME 35 10686
TEST_TIME 36 10538
TEST_TIME 37 10626
TEST_TIME 38 10804
TEST_TIME 39 10713
TEST_TIME 40 10632
TEST_TIME 41 10542
TEST_TIME 42 10407
TEST_TIME 43 10359
TEST_TIME 44 10357
TEST_TIME 45 10204
TEST_TIME 46 10205
TEST_TIME 47 10094
TEST_TIME 48 10183
TEST_TIME 49 10171
TEST_TIME 50 10118
TEST_TIME 51 10087
TEST_TIME 52 10061
TEST_TIME 53 9954
TEST_TIME 54 9851
TEST_TIME 55 9833
TEST_TIME 56 9905
TEST_TIME 57 9846
TEST_TIME 58 9747
TEST_TIME 59 9885
TEST_TIME 60 9897
TEST_TIME 61 9831
TEST_TIME 62 9846
TEST_TIME 63 9906
TEST_TIME 64 9918
TEST_TIME 65 9914
TEST_TIME 66 9985
TEST_TIME 67 9955
TEST_TIME 68 9958
TEST_TIME 69 9995
TEST_TIME 70 9964
TEST_TIME 71 9971
TEST_TIME 72 10025
TEST_TIME 73 10007
TEST_TIME 74 10030
TEST_TIME 75 9915
TEST_TIME 76 9893
TEST_TIME 77 9959
TEST_TIME 78 9879
TEST_TIME 79 9901
TEST_TIME 80 9828
TEST_TIME 81 9797
TEST_TIME 82 9834
TEST_TIME 83 9752
TEST_TIME 84 9677
TEST_TIME 85 9621
TEST_TIME 86 9625
TEST_TIME 87 9642
TEST_TIME 88 9613
TEST_TIME 89 9673
TEST_TIME 90 9708
TEST_TIME 91 9702
TEST_TIME 92 9673
TEST_TIME 93 9741
TEST_TIME 94 9714
TEST_TIME 95 9810
TEST_TIME 96 9791
TEST_TIME 97 9818
TEST_TIME 98 9882
TEST_TIME 99 9878
TEST_TIME 100 9922
TEST_TIME 101 9893
TEST_TIME 102 9822
TEST_TIME 103 9801
TEST_TIME 104 9774
TEST_TIME 105 9706
TEST_TIME 106 9671
TEST_TIME 107 9617
TEST_TIME 108 9675
TEST_TIME 109 9624
TEST_TIME 110 9589
TEST_TIME 111 9633
TEST_TIME 112 9635
TEST_TIME 113 9606
TEST_TIME 114 9619
TEST_TIME 115 9611
TEST_TIME 116 9626
TEST_TIME 117 9620
TEST_TIME 118 9597
TEST_TIME 119 9637
TEST_TIME 120 9667

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-12-2021 22:38:39 to 29-12-2021 22:38:42.

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 1605504 1605504 0 0.00 0 23360 6 0 0
doc_list_biud 1605504 1605504 0 0.00 0 48924 26 0 0
sp_add_invoice_to_stock 5437 5307 130 2.39 35 226523 17575 0 130
sp_cancel_adding_invoice 6088 6088 0 0.00 5 63756 735 0 0
sp_cancel_client_order 1855 1855 0 0.00 4 8291 284 0 0
sp_cancel_customer_reserve 588355 588355 0 0.00 1 27972 56 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 2508 62 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 3 3423 74 0 0
sp_cancel_supplier_invoice 6695 6684 11 0.16 2 19767 753 0 11
sp_cancel_supplier_order 433 433 0 0.00 5 1102514 96738 0 0
sp_cancel_write_off 10414 10414 0 0.00 1 21123 111 0 0
sp_client_order 21560 21560 0 0.00 4 21025 313 0 0
sp_customer_reserve 480953 480953 0 0.00 0 138783 178 0 0
sp_fill_shopping_cart 32878 32878 0 0.00 1 13476 40 0 0
sp_fill_shopping_cart_clo_res 480941 480941 0 0.00 0 13474 12 0 0
sp_get_clo_for_invoice 5294 5294 0 0.00 0 32809 254 0 0
sp_kill_cost_storno 17661 17661 0 0.00 0 9406 54 0 0
sp_kill_qstorno_ret_qs2qd 1194241 1194241 0 0.00 0 27874 30 0 0
sp_kill_qty_storno 629492 629492 0 0.00 0 48922 66 0 0
sp_lock_selected_doc 21611 21611 0 0.00 0 7294 4 0 0
sp_make_cost_storno 28977 28977 0 0.00 0 21784 53 0 0
sp_multiply_rows_for_pdistr 28977 28977 0 0.00 0 13007 31 0 0
sp_multiply_rows_for_qdistr 21560 21560 0 0.00 0 13118 135 0 0
sp_pay_from_customer 7327 7327 0 0.00 4 15723 123 0 0
sp_pay_to_supplier 5987 5987 0 0.00 5 15443 125 0 0
sp_payment_common 13314 13314 0 0.00 2 15715 31 0 0
sp_qd_handle_on_cancel_clo 1855 1855 0 0.00 0 8286 259 0 0
sp_qd_handle_on_invoice_upd_sts 11382 11382 0 0.00 0 48920 317 0 0
sp_qd_handle_on_reserve_upd_sts 10369 10369 0 0.00 0 18409 59 0 0
sp_reserve_write_off 10369 10369 0 0.00 6 18426 174 0 0
sp_supplier_invoice 5714 5714 0 0.00 6 99798 3398 0 0
sp_supplier_order 5625 5625 0 0.00 5 80269 1282 0 0
srv_aggregate_perf_data 123 123 0 0.00 2859 102619 14124 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2937 144878 49507 0 0
srv_find_qd_qs_mism 1110276 1110276 0 0.00 0 23359 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 37 64467 990 0 0
srv_make_money_saldo 807 807 0 0.00 1 17820 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 206935 431064 275541 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5307 5307 0 0.00 0 7999 161 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 4174 90 0 0
t$perf-norm:sp_cancel_client_order 1855 1855 0 0.00 0 2064 11 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 7091 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 569 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 232 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 933 16 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 28375 1547 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 9520 12 0 0
t$perf-norm:sp_client_order 21560 21560 0 0.00 0 7528 19 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 2199 10 0 0
t$perf-norm:sp_pay_from_customer 7327 7327 0 0.00 0 8567 11 0 0
t$perf-norm:sp_pay_to_supplier 5987 5987 0 0.00 0 4356 9 0 0
t$perf-norm:sp_reserve_write_off 10369 10369 0 0.00 0 4833 9 0 0
t$perf-norm:sp_supplier_invoice 5714 5714 0 0.00 0 7907 18 0 0
t$perf-norm:sp_supplier_order 5625 5625 0 0.00 0 6733 16 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 1080 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 252 9 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 8730 43 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 1196 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10848 10588 260 2.39 0 5298 20 0 260
v_all_customers 39192 39192 0 0.00 0 26 1 0 0
v_all_suppliers 31108 31108 0 0.00 0 14 1 0 0
v_all_wares 43120 43120 0 0.00 0 101 1 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 2579 10 0 0
v_cancel_client_order 3710 3710 0 0.00 0 3316 6 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 915 3 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 2734 11 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3384 10 0 0
v_cancel_supplier_invoice 850 828 22 2.58 0 2068 19 0 22
v_cancel_supplier_order 866 866 0 0.00 0 7406 21 0 0
v_cancel_write_off 1972 1972 0 0.00 0 6588 17 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 6744 3 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 1793 14 0 0
v_max_id_ord_sup 5696 5696 0 0.00 0 3940 5 0 0
v_max_non_paid_invoice 1751 1751 0 0.00 0 6560 6 0 0
v_max_non_paid_realizn 3990 3990 0 0.00 0 2776 4 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 8840 16 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 5199 16 0 0
v_min_id_ord_sup 5696 5696 0 0.00 0 5221 11 0 0
v_min_non_paid_invoice 5987 5987 0 0.00 0 9706 23 0 0
v_min_non_paid_realizn 7327 7327 0 0.00 0 15706 24 0 0
v_our_firm 10602 10602 0 0.00 0 16 1 0 0
v_reserve_write_off 20738 20738 0 0.00 0 6555 6 0 0
x_lock_depdocs_on_canc_invoice 12358 12358 0 0.00 0 32968 105 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 11086 344 0 0
x_make_qstorno_1000_1200 5625 5625 0 0.00 2 76703 1184 0 0
x_make_qstorno_1200_2000 5714 5714 0 0.00 2 99709 3269 0 0
x_make_qstorno_2100_3300 480953 480953 0 0.00 0 138783 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2021 22:38:44 to 29-12-2021 22:38:48.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:01 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:02 26566 8192000000 9910077680 10227249152 993028256 10.811 100 86 172 0 1022008336 6224176 22755904
20:10:35 23230 8192000000 9955484704 10282692608 1039395840 11.259 100 88 176 0 1070692896 8673632 22623424
20:12:07 23171 8192000000 9994397088 10333474816 1078434384 11.633 100 87 174 0 1111327296 10684944 22207968
20:13:35 13674 8192000000 10043897408 10392875008 1127831984 12.101 100 83 165 2 1160199120 12171984 20195152
20:14:53 8427 8192000000 10081467136 10445012992 1173751040 12.532 100 76 152 2 1204004768 12466032 17787696
20:16:05 6703 8192000000 10123052544 10493460480 1209556336 12.865 100 70 140 1 1238134064 13451024 15126704
20:17:14 4226 8192000000 10153680672 10533466112 1247849616 13.218 100 78 155 1 1278307376 13158608 17299152
20:18:21 1648 8192000000 10208416096 10588737536 1291437888 13.617 100 92 185 2 1325794240 13554032 20802320
20:19:24 19053 8192000000 10214773216 10604367872 1299235424 13.688 100 75 150 3 1325146784 8613600 17297760
20:20:44 8473 8192000000 10197354352 10609700864 1310700768 13.792 100 36 73 2 1326504560 6979664 8824128
20:21:53 11556 8192000000 10199673216 10620006400 1322890480 13.903 100 20 40 1 1333786960 6360480 4536000
20:23:08 7591 8192000000 10214243936 10631495680 1328381760 13.953 100 15 29 0 1337434208 5635664 3416784
20:24:18 51670 8192000000 10222011440 10646757376 1330912992 13.975 100 28 56 1 1343208416 6193248 6102176
20:26:13 5712 8192000000 10238202608 10657841152 1338588176 14.045 100 27 53 2 1350892736 6142080 6162480
20:27:19 10234 8192000000 10226402800 10648342528 1325361600 13.925 99 13 26 1 1333922640 5972736 2588304
20:28:30 33067 8192000000 10205320528 10624978944 1270661024 13.428 96 29 57 1 1286869936 9509664 6699248
20:30:25 4721 8192000000 10123459792 10515718144 1149973952 12.309 91 25 50 0 1161952192 5567168 6411072
20:31:34 7652 8192000000 10130135072 10531844096 1171652400 12.512 93 26 53 2 1182625456 5003568 5969488
20:32:42 15746 8192000000 10161514768 10563768320 1248148480 13.221 98 14 28 1 1256770848 5210672 3411696
20:33:58 53904 8192000000 10169188176 10582257664 1278992288 13.504 100 29 58 0 1291184704 5783024 6409392
20:35:58 8466 8192000000 10188708608 10605703168 1300062960 13.696 100 35 69 2 1314679216 7100560 7515696
20:37:07 11280 8192000000 10198000256 10619760640 1312714880 13.811 100 14 28 1 1320956176 4855824 3385472
20:38:23 11667 8192000000 10235717072 10642976768 1334178160 14.005 100 46 92 1 1349956304 5442096 10336048
20:39:45 46580 8192000000 10267912832 10678747136 1354521408 14.188 100 38 76 1 1375758816 12889312 8348096
20:41:34 68062 8192000000 10277473376 10699542528 1369878752 14.326 100 35 70 1 1392852336 14774304 8199280
20:43:49 51683 8192000000 10288678784 10718580736 1366144048 14.292 99 35 68 1 1389127184 14778672 8204464
20:46:03 6919 8192000000 10309667520 10730307584 1389548304 14.502 100 46 92 0 1417213104 16831088 10833712
20:47:15 4283 8192000000 10323320112 10747092992 1400396208 14.599 100 59 117 0 1438928496 24480976 14051312
20:48:26 6116 8192000000 10340857696 10760830976 1399039648 14.586 99 67 134 2 1440195392 25205712 15950032
20:49:35 3748 8192000000 10349862064 10769375232 1402828064 14.620 99 63 127 2 1449776880 30773296 16175520
20:50:42 10230 8192000000 10329755536 10745679872 1388771584 14.495 100 80 158 2 1436635184 28976960 18886640
20:51:57 5392 8192000000 10346119312 10755624960 1393684432 14.539 100 84 165 2 1447192448 32959440 20548576
20:53:10 4089 8192000000 10306891328 10713456640 1351733888 14.163 99 66 132 1 1402380352 34270912 16375552
20:54:20 3238 8192000000 10333816096 10741940224 1373435680 14.358 100 81 158 2 1433126352 39578464 20112208
20:55:32 10759 8192000000 10330729568 10737041408 1367878672 14.308 100 73 146 3 1431483248 45192208 18412368
20:56:49 2937 8192000000 10353073776 10752700416 1373519552 14.359 100 91 183 1 1451564624 54654160 23390912
20:57:57 6179 8192000000 10309468928 10725797888 1343283040 14.087 98 57 114 0 1409067568 50575552 15208976
20:59:08 4603 8192000000 10334778720 10752929792 1369684208 14.324 100 55 111 2 1440124368 56445056 13995104
21:00:19 8476 8192000000 10348499920 10769379328 1380633248 14.422 100 50 100 1 1452924432 59465376 12825808
21:01:31 4447 8192000000 10352647152 10773098496 1389413520 14.501 100 49 98 1 1456917104 54596272 12907312
21:02:46 6699 8192000000 10366625440 10785013760 1385393888 14.465 100 56 110 1 1466627488 66493312 14740288
21:03:55 19545 8192000000 10348012960 10781745152 1389246592 14.499 100 34 68 3 1453921312 55008736 9665984
21:05:31 30801 8192000000 10336429920 10776215552 1391384032 14.518 100 30 58 0 1442659968 43483072 7792864
21:07:02 30389 8192000000 10323269984 10780131328 1372585184 14.350 100 19 39 1 1433705088 55305392 5814512
21:09:19 67679 8192000000 10299187168 10750668800 1387200320 14.481 100 10 20 1 1404218864 14801280 2217264
21:11:29 99397 8192000000 10313613984 10748055552 1336682192 14.027 96 15 30 1 1349146128 9466672 2997264
21:14:09 82614 8192000000 10309669104 10771390464 1392075504 14.524 100 12 24 1 1420460432 25541776 2843152
21:16:34 71735 8192000000 10307188512 10769772544 1400070320 14.596 100 11 22 1 1421871776 19296064 2505392
21:18:54 100034 8192000000 10327008256 10782441472 1391562000 14.520 99 25 50 0 1411609232 14603856 5443376
21:21:38 75652 8192000000 10307261312 10761707520 1400057728 14.596 100 37 72 2 1415431120 7559744 7813648
21:23:54 43723 8192000000 10319679072 10780721152 1411256992 14.695 100 10 20 1 1423047312 9299632 2490688
21:25:42 37622 8192000000 10310786640 10764075008 1380320048 14.419 98 39 78 1 1398554064 9380896 8853120
21:27:23 144878 8192000000 10284940320 10760568832 1340027984 14.058 95 13 26 0 1357557472 14076352 3453136
21:30:48 73331 8192000000 10296888048 10759946240 1379288752 14.410 99 26 52 2 1400057776 14505024 6264000
21:33:02 46290 8192000000 10285271152 10758303744 1360736864 14.244 98 18 36 4 1380383552 15710336 3936352
21:34:49 75248 8192000000 10276732816 10745884672 1357479520 14.215 98 18 36 2 1377716176 16233984 4002672
21:37:04 76711 8192000000 10248218848 10697191424 1250683136 13.244 91 12 25 0 1263079600 9808832 2587632
21:39:22 49670 8192000000 10268133248 10721443840 1340017600 14.058 98 17 34 0 1354726704 10563696 4145408
21:41:12 23718 8192000000 10286660752 10735230976 1354709536 14.190 98 18 37 0 1366207680 7401312 4096832
21:42:36 22159 8192000000 10293680496 10737885184 1369107504 14.319 100 39 77 0 1389636464 11864672 8664288
21:44:00 42647 8192000000 10278992032 10723311616 1341343152 14.070 98 29 58 2 1357886064 10234672 6308240
21:45:43 39174 8192000000 10274028192 10713681920 1324766608 13.920 97 27 54 0 1344779104 13958448 6054048
21:47:24 64566 8192000000 10272344976 10715516928 1322971456 13.904 97 20 40 0 1340952576 13531312 4449808
21:49:37 126475 8192000000 10282337696 10722590720 1285375168 13.562 93 14 28 1 1310339408 22410256 2553984
21:52:47 102236 8192000000 10269932160 10718785536 1327922320 13.948 97 23 46 0 1344375296 10636336 5816640
21:55:30 21069 8192000000 10278212576 10723872768 1367211408 14.302 100 20 38 2 1382814064 11688368 3914288
21:56:54 59390 8192000000 10264558288 10728271872 1348831664 14.137 98 7 14 0 1359255696 8982704 1441328
21:59:00 54685 8192000000 10266894480 10735308800 1368809040 14.316 100 12 24 1 1383456960 11878528 2769392
22:00:55 51049 8192000000 10263774896 10713235456 1354872288 14.191 99 12 23 0 1367632608 10685856 2074464
22:02:50 85734 8192000000 10298180400 10769416192 1393078256 14.533 100 19 38 0 1414233024 16450352 4704416
22:05:16 37228 8192000000 10295681072 10752589824 1387949968 14.488 100 21 42 0 1410382720 16714032 5718720
22:07:00 59471 8192000000 10289745600 10753658880 1373101136 14.355 99 18 36 0 1389263248 12300560 3861552
22:09:05 62435 8192000000 10301053088 10770460672 1395094304 14.551 100 20 40 0 1412239168 13078704 4066160
22:11:25 45780 8192000000 10322479024 10775318528 1400343344 14.598 100 29 58 1 1419669376 12750624 6575408
22:13:20 46103 8192000000 10297732384 10751660032 1370025936 14.327 98 26 52 2 1386037440 10294912 5716592
22:15:12 144466 8192000000 10311522672 10773123072 1327446928 13.944 95 11 22 0 1344823360 15088000 2288432
22:18:49 138057 8192000000 10275424608 10740490240 1360441168 14.241 98 32 64 1 1383761168 15992656 7327344
22:22:07 54960 8192000000 10272705216 10744684544 1371294656 14.339 99 8 16 0 1384694480 11504384 1895440
22:24:03 68628 8192000000 10266355680 10729488384 1332503488 13.990 97 8 16 0 1350789008 16409216 1876304
22:26:20 58690 8192000000 10267515488 10737762304 1337392352 14.034 97 17 34 2 1353681904 12547312 3742240
22:28:35 66439 8192000000 10262298128 10718973952 1327577440 13.945 96 13 26 0 1343238960 12430960 3230560
22:30:42 71360 8192000000 10262718720 10720907264 1320061504 13.877 96 34 68 3 1336372240 8643584 7667152
22:32:59 41653 8192000000 10257388880 10707591168 1315326624 13.834 96 20 40 2 1330855376 11111584 4417168
22:34:41 35538 8192000000 10272296432 10716110848 1332817648 13.993 98 34 67 1 1349589904 9017600 7754656
22:36:17 103688 8192000000 9386815696 9694416896 529565280 6.071 39 6 12 0 536284256 5455008 1263968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 29-12-2021 22:38:50 to 29-12-2021 22:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2021 22:39:05 to 29-12-2021 22:39:07.

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          247030
MON$OLDEST_ACTIVE               247031
MON$OLDEST_SNAPSHOT             247031
MON$NEXT_TRANSACTION            247031
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                       2052448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:88: 
:89: mscs-test-161.budzdorov.ru    Wed Dec 29 22:38:03 2021
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
--- end of diff output ---

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

29.12.21 22:39:08. 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-12-29 22:39:10 No severe PSQL-related problems occured
29.12.21 22:39:12. End of report.