Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2021 10:28
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16798920 kB
MemAvailable 31277160 kB
Buffers 0 kB
Cached 14800392 kB
SwapCached 4864 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 4.0.0
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-12 10:26:55
DTS_END                         2021-01-12 10:26:55.9570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52092
TRN_ID                          228738
ATT_ID                          592
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: 8479.36 25624 1017523
customer order: creation 157.70 383 18924
customer order: refuse 13.75 309 1650
order to supplier: creation 42.95 1298 5154
order to supplier: removal 3.18 102840 381
invoice (draft): creation 42.99 3867 5159
invoice (draft): removal 47.67 861 5720
invoice accept: apply 37.92 16550 4551
invoice accept: cancel 43.05 832 5166
customer reserve: creation 3564.26 158 427711
customer reserve: removal 4247.88 61 509746
realization accept: apply 77.89 199 9347
realization accept: cancel 81.42 114 9770
payment from customer: creation 54.83 119 6579
payment from customer: removal 5.41 99 649
payment to supplier: creation 45.42 123 5450
payment to supplier: removal 3.27 96 392
service: total inventory turnovers 3.93 3145 471
service: total monetary turnovers 5.07 546 608
service: aggregate perf. data 0.76 21071 91
service: refresh index statistics 0.03 359818 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2021 10:28:24 to 12-01-2021 10:28:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2938
TEST_TIME 2 2945
TEST_TIME 3 3658
TEST_TIME 4 4118
TEST_TIME 5 4829
TEST_TIME 6 4710
TEST_TIME 7 4929
TEST_TIME 8 5313
TEST_TIME 9 5119
TEST_TIME 10 5438
TEST_TIME 11 5573
TEST_TIME 12 5759
TEST_TIME 13 5985
TEST_TIME 14 6264
TEST_TIME 15 6432
TEST_TIME 16 7034
TEST_TIME 17 7171
TEST_TIME 18 7715
TEST_TIME 19 8253
TEST_TIME 20 8126
TEST_TIME 21 8457
TEST_TIME 22 9017
TEST_TIME 23 9031
TEST_TIME 24 9197
TEST_TIME 25 9783
TEST_TIME 26 9991
TEST_TIME 27 9737
TEST_TIME 28 10013
TEST_TIME 29 9912
TEST_TIME 30 9695
TEST_TIME 31 9672
TEST_TIME 32 9523
TEST_TIME 33 9334
TEST_TIME 34 9353
TEST_TIME 35 9344
TEST_TIME 36 9217
TEST_TIME 37 9126
TEST_TIME 38 9011
TEST_TIME 39 8826
TEST_TIME 40 8849
TEST_TIME 41 8899
TEST_TIME 42 8929
TEST_TIME 43 8912
TEST_TIME 44 8900
TEST_TIME 45 8913
TEST_TIME 46 8846
TEST_TIME 47 9147
TEST_TIME 48 9099
TEST_TIME 49 9103
TEST_TIME 50 9146
TEST_TIME 51 9123
TEST_TIME 52 9055
TEST_TIME 53 9067
TEST_TIME 54 9139
TEST_TIME 55 8978
TEST_TIME 56 8841
TEST_TIME 57 8906
TEST_TIME 58 8817
TEST_TIME 59 8736
TEST_TIME 60 8673
TEST_TIME 61 8630
TEST_TIME 62 8597
TEST_TIME 63 8636
TEST_TIME 64 8723
TEST_TIME 65 8718
TEST_TIME 66 8688
TEST_TIME 67 8806
TEST_TIME 68 8726
TEST_TIME 69 8789
TEST_TIME 70 8833
TEST_TIME 71 8899
TEST_TIME 72 8884
TEST_TIME 73 8871
TEST_TIME 74 8827
TEST_TIME 75 8879
TEST_TIME 76 8899
TEST_TIME 77 8839
TEST_TIME 78 8764
TEST_TIME 79 8752
TEST_TIME 80 8697
TEST_TIME 81 8664
TEST_TIME 82 8625
TEST_TIME 83 8608
TEST_TIME 84 8567
TEST_TIME 85 8559
TEST_TIME 86 8556
TEST_TIME 87 8552
TEST_TIME 88 8589
TEST_TIME 89 8650
TEST_TIME 90 8597
TEST_TIME 91 8629
TEST_TIME 92 8725
TEST_TIME 93 8690
TEST_TIME 94 8701
TEST_TIME 95 8809
TEST_TIME 96 8764
TEST_TIME 97 8816
TEST_TIME 98 8736
TEST_TIME 99 8676
TEST_TIME 100 8642
TEST_TIME 101 8632
TEST_TIME 102 8617
TEST_TIME 103 8593
TEST_TIME 104 8599
TEST_TIME 105 8578
TEST_TIME 106 8567
TEST_TIME 107 8580
TEST_TIME 108 8648
TEST_TIME 109 8699
TEST_TIME 110 8678
TEST_TIME 111 8634
TEST_TIME 112 8712
TEST_TIME 113 8719
TEST_TIME 114 8656
TEST_TIME 115 8609
TEST_TIME 116 8559
TEST_TIME 117 8518
TEST_TIME 118 8505
TEST_TIME 119 8505
TEST_TIME 120 8482

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-01-2021 10:28:27 to 12-01-2021 10:28:31.

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 1413014 1413014 0 0.00 0 20841 6 0 0
doc_list_biud 1413014 1413014 0 0.00 0 122096 28 0 0
sp_add_invoice_to_stock 4699 4551 148 3.14 35 165137 16550 0 148
sp_cancel_adding_invoice 5166 5166 0 0.00 4 60306 832 0 0
sp_cancel_client_order 1650 1650 0 0.00 4 9531 310 0 0
sp_cancel_customer_reserve 509746 509746 0 0.00 1 76113 61 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 13001 100 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 4960 96 0 0
sp_cancel_supplier_invoice 5730 5720 10 0.17 3 122102 861 0 10
sp_cancel_supplier_order 381 381 0 0.00 11 957597 102841 0 0
sp_cancel_write_off 9770 9770 0 0.00 1 16015 115 0 0
sp_client_order 18924 18924 0 0.00 4 36349 383 0 0
sp_customer_reserve 427711 427711 0 0.00 0 44715 159 0 0
sp_fill_shopping_cart 29227 29227 0 0.00 1 21810 49 0 0
sp_fill_shopping_cart_clo_res 427705 427705 0 0.00 0 23977 17 0 0
sp_get_clo_for_invoice 4545 4545 0 0.00 0 40597 475 0 0
sp_kill_cost_storno 15977 15977 0 0.00 0 17634 56 0 0
sp_kill_qstorno_ret_qs2qd 1035363 1035363 0 0.00 0 122095 33 0 0
sp_kill_qty_storno 546325 546325 0 0.00 0 122096 72 0 0
sp_lock_selected_doc 19289 19289 0 0.00 0 8768 5 0 0
sp_make_cost_storno 25921 25921 0 0.00 0 20839 62 0 0
sp_multiply_rows_for_pdistr 25921 25921 0 0.00 0 12445 30 0 0
sp_multiply_rows_for_qdistr 18924 18924 0 0.00 0 36336 212 0 0
sp_pay_from_customer 6579 6579 0 0.00 4 20847 120 0 0
sp_pay_to_supplier 5450 5450 0 0.00 5 19553 124 0 0
sp_payment_common 12029 12029 0 0.00 1 16852 28 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 9510 286 0 0
sp_qd_handle_on_invoice_upd_sts 9711 9711 0 0.00 0 29586 359 0 0
sp_qd_handle_on_reserve_upd_sts 9347 9347 0 0.00 0 16004 64 0 0
sp_reserve_write_off 9347 9347 0 0.00 6 20848 199 0 0
sp_supplier_invoice 5159 5159 0 0.00 8 103891 3868 0 0
sp_supplier_order 5154 5154 0 0.00 5 59887 1299 0 0
srv_aggregate_perf_data 91 91 0 0.00 2723 497507 21071 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 3016 133656 52038 0 0
srv_find_qd_qs_mism 973137 973137 0 0.00 0 10817 5 0 0
srv_make_invnt_saldo 471 471 0 0.00 58 115856 3146 0 0
srv_make_money_saldo 608 608 0 0.00 1 14320 546 0 0
srv_recalc_idx_stat 4 4 0 0.00 77885 761621 359818 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 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 4551 4551 0 0.00 0 51857 166 0 0
t$perf-norm:sp_cancel_adding_invoice 370 370 0 0.00 0 3907 78 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 563 8 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 2887 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 986 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1172 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 1905 14 0 0
t$perf-norm:sp_cancel_supplier_order 381 381 0 0.00 0 17579 1472 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 663 4 0 0
t$perf-norm:sp_client_order 18924 18924 0 0.00 0 4466 14 0 0
t$perf-norm:sp_customer_reserve 939 939 0 0.00 0 1296 4 0 0
t$perf-norm:sp_pay_from_customer 6579 6579 0 0.00 0 28814 13 0 0
t$perf-norm:sp_pay_to_supplier 5450 5450 0 0.00 0 2866 9 0 0
t$perf-norm:sp_reserve_write_off 9347 9347 0 0.00 0 3629 7 0 0
t$perf-norm:sp_supplier_invoice 5159 5159 0 0.00 0 3150 18 0 0
t$perf-norm:sp_supplier_order 5154 5154 0 0.00 0 5691 14 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 229 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 604 14 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 806 7 0 0
t$perf-norm:srv_make_money_saldo 608 608 0 0.00 0 1706 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9386 9090 296 3.15 0 4362 17 0 296
v_all_customers 34570 34570 0 0.00 0 49 1 0 0
v_all_suppliers 28500 28500 0 0.00 0 15 1 0 0
v_all_wares 37848 37848 0 0.00 0 215 1 0 0
v_cancel_adding_invoice 740 740 0 0.00 0 149 5 0 0
v_cancel_client_order 3300 3300 0 0.00 0 720 2 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 3882 11 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 2029 4 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 4060 9 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 217 12 0 20
v_cancel_supplier_order 762 762 0 0.00 0 4667 13 0 0
v_cancel_write_off 1892 1892 0 0.00 0 4823 18 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 4678 3 0 0
v_max_id_clo_res 939 939 0 0.00 0 1766 10 0 0
v_max_id_ord_sup 5149 5149 0 0.00 0 5730 6 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 1984 4 0 0
v_max_non_paid_realizn 3566 3566 0 0.00 0 3507 4 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 4311 6 0 0
v_min_id_clo_res 939 939 0 0.00 0 6633 16 0 0
v_min_id_ord_sup 5149 5149 0 0.00 0 1951 4 0 0
v_min_non_paid_invoice 5450 5450 0 0.00 0 16849 19 0 0
v_min_non_paid_realizn 6579 6579 0 0.00 0 12463 21 0 0
v_our_firm 9304 9304 0 0.00 0 16 1 0 0
v_reserve_write_off 18694 18694 0 0.00 0 3934 5 0 0
x_lock_depdocs_on_canc_invoice 10498 10498 0 0.00 0 46161 108 0 0
x_lock_depdocs_on_canc_sup_ord 381 381 0 0.00 0 2718 289 0 0
x_make_qstorno_1000_1200 5154 5154 0 0.00 2 58109 1221 0 0
x_make_qstorno_1200_2000 5159 5159 0 0.00 3 103883 3674 0 0
x_make_qstorno_2100_3300 427711 427711 0 0.00 0 44714 139 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2021 10:28:32 to 12-01-2021 10:28:36.

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
07:56:59 6 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
07:58:01 19363 8192000000 9716031072 10010124288 793570304 8.831 100 89 178 0 822714880 7761024 21383552
07:59:30 21588 8192000000 9758792864 10064400384 837653296 9.276 100 88 176 1 869699200 11018592 21027312
08:00:56 11355 8192000000 9798697792 10114371584 871134832 9.611 100 84 168 0 907231152 16915408 19180912
08:02:12 14839 8192000000 9858095328 10191884288 918251424 10.079 100 79 158 1 964737584 29209248 17276912
08:03:34 8057 8192000000 9928849728 10272747520 963666704 10.525 100 77 151 2 1034072368 54807856 15597808
08:04:43 4725 8192000000 9964313632 10317004800 991256064 10.794 100 89 178 0 1078974544 70194032 17524448
08:05:50 3158 8192000000 9975883760 10341609472 1018150512 11.054 100 83 163 0 1102205024 67033504 17021008
08:06:55 1383 8192000000 10015824640 10381656064 1042492080 11.289 100 91 179 0 1128416432 68191904 17732448
08:07:58 2369 8192000000 10028033872 10399850496 1068929952 11.542 100 84 166 1 1147037648 60654736 17452960
08:09:02 6750 8192000000 9994835568 10383994880 1066782432 11.521 100 38 75 1 1132186304 57672272 7731600
08:10:10 6090 8192000000 10013668864 10404282368 1078317872 11.631 100 44 88 0 1121288272 33662432 9307968
08:11:20 6318 8192000000 9992452000 10396712960 1083194944 11.678 100 28 56 0 1114108864 24891744 6022176
08:12:28 60254 8192000000 9997444288 10400194560 1088230224 11.726 100 33 66 2 1105827760 10656048 6941488
08:14:33 3440 8192000000 10018207120 10418532352 1098252320 11.821 100 35 69 2 1132770752 27190464 7327968
08:15:40 2476 8192000000 10015009408 10411868160 1099572496 11.834 100 52 107 2 1130255936 18629392 12054048
08:16:43 3530 8192000000 10001547536 10390704128 1084895920 11.694 99 37 73 1 1103469296 10994384 7578992
08:17:48 44326 8192000000 9958292896 10352885760 962818816 10.517 88 38 75 1 996517152 25737984 7960352
08:19:37 4093 8192000000 9915443488 10295308288 968410320 10.571 93 54 110 1 987082304 5618432 13053552
08:20:43 4665 8192000000 9921671712 10295611392 986376160 10.746 98 40 79 1 1012565888 16603776 9585952
08:21:49 5115 8192000000 9944083280 10331389952 1018393024 11.056 100 43 87 1 1045322800 17194368 9735408
08:22:55 50382 8192000000 9949593056 10340175872 1029421168 11.163 99 25 48 2 1045577408 11058624 5097616
08:24:46 9448 8192000000 9986927168 10386825216 1055351664 11.412 100 33 67 1 1082599664 20177552 7070448
08:25:56 6873 8192000000 9991355744 10393034752 1065670560 11.511 100 41 80 1 1090639952 15404352 9565040
08:27:05 9590 8192000000 10004626208 10401112064 1095459600 11.795 100 36 74 1 1112315824 9460016 7396208
08:28:21 67514 8192000000 10067798640 10470785024 1117183856 12.000 100 44 88 0 1187145568 60274288 9687424
08:30:44 8124 8192000000 10116727680 10527825920 1138228704 12.199 100 47 94 0 1231996688 83398464 10369520
08:31:56 13716 8192000000 10139423600 10546987008 1148144400 12.292 100 48 93 2 1248948528 90640288 10163840
08:33:18 4880 8192000000 10168550592 10572197888 1155787424 12.364 100 65 127 1 1284393488 115095824 13510240
08:34:32 38628 8192000000 10181370464 10617995264 1159729904 12.401 100 39 78 1 1296731008 127600000 9401104
08:36:20 22301 8192000000 10195657824 10615091200 1165078864 12.451 100 42 84 2 1313732928 138846736 9807328
08:37:46 4944 8192000000 10233978256 10644254720 1159629760 12.400 99 57 113 0 1326822048 154640496 12551792
08:38:57 3277 8192000000 10258792320 10664017920 1169015904 12.488 100 79 155 1 1358446576 171515680 17914992
08:40:04 3804 8192000000 10262741456 10681212928 1175177872 12.545 100 83 162 2 1362095168 168884704 18032592
08:41:19 3156 8192000000 10305702640 10714038272 1169752240 12.495 100 67 134 0 1409784272 225377136 14654896
08:42:28 20408 8192000000 10314110736 10738384896 1141776720 12.232 98 34 67 3 1399885456 249656256 8452480
08:43:52 3012 8192000000 10298574544 10705567744 1130849312 12.129 99 73 146 1 1390128320 241662032 17616976
08:44:59 4569 8192000000 10255886992 10678009856 1120793744 12.034 98 55 112 2 1344587536 210631088 13162704
08:46:11 13144 8192000000 10228457824 10637455360 1116932352 11.998 100 33 65 1 1327343248 202703744 7707152
08:47:37 22328 8192000000 10230283216 10699366400 1119969728 12.027 99 25 50 0 1354605520 227845408 6790384
08:49:08 21621 8192000000 10201175168 10634457088 1104364704 11.879 99 22 44 2 1287724384 177944032 5415648
08:50:31 11525 8192000000 10235715392 10671378432 1115027168 11.980 100 30 60 0 1338838768 216098064 7713536
08:51:51 24476 8192000000 10154795536 10584817664 1099990832 11.838 98 14 27 1 1249519200 146588304 2940064
08:53:16 79694 8192000000 10145065232 10631188480 1115907232 11.988 99 21 42 2 1242088000 121740496 4440272
08:55:37 63154 8192000000 10096609200 10542346240 1099957888 11.837 98 24 49 1 1189423104 84010704 5454512
08:57:49 133655 8192000000 10097506032 10534879232 1109139120 11.924 98 18 35 0 1180209200 67618416 3451664
09:01:04 117250 8192000000 10062536880 10520887296 1091327472 11.755 96 11 22 0 1132507984 39268704 1911808
09:05:08 58715 8192000000 10106615216 10575360000 1133434288 12.154 100 21 42 1 1206106224 68135840 4536096
09:07:14 59071 8192000000 10072148544 10596306944 1133999072 12.159 100 8 16 1 1200032384 64521520 1511792
09:09:25 23486 8192000000 10119549344 10570711040 1137855872 12.195 100 44 88 1 1209782176 62832048 9094256
09:11:08 58116 8192000000 10146037232 10613018624 1143355808 12.247 100 17 34 0 1236883744 89541280 3986656
09:13:08 93373 8192000000 10086166976 10532995072 1147335520 12.284 100 12 24 0 1214142224 64318528 2488176
09:15:53 52824 8192000000 10101607968 10569416704 1150587568 12.315 100 14 28 1 1197648080 44180032 2880480
09:17:47 69304 8192000000 10126379024 10559115264 1148476880 12.295 99 33 63 1 1195048352 39949584 6621888
09:20:03 123266 8192000000 10119791808 10605985792 1149359072 12.303 99 15 30 1 1200107648 47685488 3063088
09:23:16 57227 8192000000 10077706608 10584846336 1125417040 12.078 97 7 14 0 1171388928 44441776 1530112
09:25:13 29244 8192000000 10084994304 10571538432 1142512160 12.239 99 7 14 0 1187381504 43662752 1206592
09:26:43 74444 8192000000 10100947488 10547724288 1126109808 12.085 99 10 20 0 1186452160 57995664 2346688
09:28:57 75019 8192000000 10113698112 10575785984 1130331072 12.124 99 15 30 0 1209390128 76095280 2963776
09:31:13 72298 8192000000 10068088800 10521935872 1051614608 11.376 92 12 24 0 1085382160 31613728 2153824
09:33:25 98570 8192000000 10141590544 10570502144 1127963280 12.102 99 32 64 3 1219760160 85312864 6484016
09:36:12 101644 8192000000 10028889008 10458419200 1001013760 10.888 89 16 33 2 1087706784 83639072 3053952
09:39:00 28172 8192000000 10088105872 10523414528 1102132544 11.858 99 40 80 2 1158383008 48655888 7594576
09:40:32 58096 8192000000 10089592080 10516004864 1033772368 11.205 93 32 64 1 1124275440 83999232 6503840
09:42:33 106814 8192000000 10074881024 10566164480 1097155632 11.811 100 19 38 0 1179936448 78528048 4252768
09:45:47 40326 8192000000 10030139856 10480766976 1095451104 11.794 99 14 28 0 1121513312 23172928 2889280
09:47:36 67138 8192000000 10030570400 10506031104 1091891472 11.761 98 6 12 0 1122813696 29717968 1204256
09:50:11 57580 8192000000 10054194064 10496237568 1096514864 11.805 99 9 16 1 1124512112 26307648 1689600
09:52:19 51030 8192000000 10084865728 10526031872 1108564208 11.919 99 23 46 0 1186501312 72730640 5206464
09:54:13 72682 8192000000 10091463056 10549465088 1124810128 12.072 100 21 41 0 1192413040 63151552 4451360
09:56:34 130235 8192000000 10077468656 10592124928 1088882272 11.732 96 15 30 0 1201465936 109453184 3130480
09:59:49 22770 8192000000 10102597536 10515832832 1108194496 11.915 99 42 84 0 1167765680 51216288 8354896
10:01:12 66633 8192000000 10070791632 10576039936 1115550496 11.985 99 20 40 1 1214753088 95088640 4113952
10:03:19 85100 8192000000 10077520096 10567868416 1131015056 12.131 100 10 20 0 1211402704 78650080 1737568
10:05:50 32461 8192000000 10073298496 10561773568 1134058800 12.160 100 7 14 0 1171129600 35677088 1393712
10:08:35 88118 8192000000 10093689360 10547154944 1095452864 11.794 96 19 38 0 1162440656 62457856 4529936
10:11:05 55024 8192000000 10115203264 10566410240 1142071888 12.235 100 28 56 0 1202531040 54598928 5860224
10:13:07 55802 8192000000 10168828864 10647584768 1157150400 12.377 100 19 39 2 1272418000 110453856 4813744
10:15:03 65634 8192000000 10092545536 10583916544 1142095152 12.235 99 33 64 1 1213812272 64267360 7449760
10:17:15 46647 8192000000 10117148960 10569867264 1149354432 12.303 100 29 58 0 1225712320 70206240 6151648
10:19:04 86080 8192000000 10093533920 10523955200 1105943568 11.894 96 23 45 0 1142021120 31139536 4938016
10:22:11 86273 8192000000 10109874352 10573381632 1140051936 12.216 99 11 22 0 1196867248 54171808 2643504
10:24:43 39796 8192000000 10067668560 10518048768 1112734848 11.958 98 25 48 0 1143488304 25501584 5251872
10:26:31 38313 8192000000 9094681008 9393553408 235895264 2.798 21 1 2 0 253984096 18053424 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-01-2021 10:28:38 to 12-01-2021 10:28:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 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 12-01-2021 10:28:53 to 12-01-2021 10:28:55.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          229019
MON$OLDEST_ACTIVE               229020
MON$OLDEST_SNAPSHOT             229020
MON$NEXT_TRANSACTION            229020
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-30 07:30:08.6030 GMT
MON$PAGES                       2083040
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             780
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2324 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.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 ---
:139: 
:140: mscs-test-161.budzdorov.ru    Tue Jan 12 10:27:16 2021
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
:143: 
:144: mscs-test-161.budzdorov.ru    Tue Jan 12 10:27:23 2021
:145:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:146: 
--- end of diff output ---

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

12.01.21 10:28:56. There are 3 logs in /var/tmp/logs-oltp40 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-01-12 10:28:59 No severe PSQL-related problems occured
12.01.21 10:29:00. End of report.