Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.03.2021 07: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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 17185748 kB
MemAvailable 31650600 kB
Buffers 0 kB
Cached 14518912 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 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-03-08 07:26:55
DTS_END                         2021-03-08 07:26:55.6940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/57484
TRN_ID                          224250
ATT_ID                          656
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: 8652.78 24625 1038333
customer order: creation 156.01 395 18721
customer order: refuse 13.64 314 1637
order to supplier: creation 40.63 1339 4875
order to supplier: removal 3.13 122961 376
invoice (draft): creation 41.20 3767 4944
invoice (draft): removal 50.19 861 6023
invoice accept: apply 37.92 17910 4551
invoice accept: cancel 45.25 819 5430
customer reserve: creation 3546.68 172 425601
customer reserve: removal 4441.26 69 532951
realization accept: apply 76.51 186 9181
realization accept: cancel 84.06 116 10087
payment from customer: creation 54.01 109 6481
payment from customer: removal 5.69 148 683
payment to supplier: creation 43.87 115 5264
payment to supplier: removal 3.11 77 373
service: total inventory turnovers 3.20 3805 384
service: total monetary turnovers 5.59 587 671
service: aggregate perf. data 0.80 16500 96
service: refresh index statistics 0.03 322248 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-03-2021 07:28:46 to 08-03-2021 07:28:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4342
TEST_TIME 2 4568
TEST_TIME 3 3819
TEST_TIME 4 3852
TEST_TIME 5 4014
TEST_TIME 6 4869
TEST_TIME 7 5565
TEST_TIME 8 5461
TEST_TIME 9 5863
TEST_TIME 10 5868
TEST_TIME 11 5989
TEST_TIME 12 5987
TEST_TIME 13 5984
TEST_TIME 14 6410
TEST_TIME 15 6713
TEST_TIME 16 6923
TEST_TIME 17 6964
TEST_TIME 18 7258
TEST_TIME 19 7551
TEST_TIME 20 7842
TEST_TIME 21 8219
TEST_TIME 22 8612
TEST_TIME 23 8891
TEST_TIME 24 8951
TEST_TIME 25 9220
TEST_TIME 26 9260
TEST_TIME 27 9674
TEST_TIME 28 10091
TEST_TIME 29 10702
TEST_TIME 30 10563
TEST_TIME 31 10226
TEST_TIME 32 9998
TEST_TIME 33 9952
TEST_TIME 34 9893
TEST_TIME 35 9706
TEST_TIME 36 9584
TEST_TIME 37 9545
TEST_TIME 38 9618
TEST_TIME 39 9421
TEST_TIME 40 9409
TEST_TIME 41 9238
TEST_TIME 42 9115
TEST_TIME 43 8972
TEST_TIME 44 8980
TEST_TIME 45 9076
TEST_TIME 46 8989
TEST_TIME 47 9031
TEST_TIME 48 8917
TEST_TIME 49 9026
TEST_TIME 50 9109
TEST_TIME 51 9050
TEST_TIME 52 9090
TEST_TIME 53 9276
TEST_TIME 54 9261
TEST_TIME 55 9287
TEST_TIME 56 9315
TEST_TIME 57 9252
TEST_TIME 58 9101
TEST_TIME 59 9032
TEST_TIME 60 9032
TEST_TIME 61 8962
TEST_TIME 62 8928
TEST_TIME 63 8903
TEST_TIME 64 8876
TEST_TIME 65 9040
TEST_TIME 66 9020
TEST_TIME 67 9031
TEST_TIME 68 9024
TEST_TIME 69 8992
TEST_TIME 70 9035
TEST_TIME 71 9136
TEST_TIME 72 9234
TEST_TIME 73 9243
TEST_TIME 74 9249
TEST_TIME 75 9326
TEST_TIME 76 9283
TEST_TIME 77 9168
TEST_TIME 78 9117
TEST_TIME 79 9054
TEST_TIME 80 8992
TEST_TIME 81 8996
TEST_TIME 82 8948
TEST_TIME 83 8893
TEST_TIME 84 8839
TEST_TIME 85 8764
TEST_TIME 86 8724
TEST_TIME 87 8726
TEST_TIME 88 8688
TEST_TIME 89 8738
TEST_TIME 90 8832
TEST_TIME 91 8908
TEST_TIME 92 9011
TEST_TIME 93 8973
TEST_TIME 94 8974
TEST_TIME 95 8953
TEST_TIME 96 8987
TEST_TIME 97 9038
TEST_TIME 98 9034
TEST_TIME 99 8968
TEST_TIME 100 8912
TEST_TIME 101 8879
TEST_TIME 102 8819
TEST_TIME 103 8800
TEST_TIME 104 8815
TEST_TIME 105 8806
TEST_TIME 106 8818
TEST_TIME 107 8807
TEST_TIME 108 8790
TEST_TIME 109 8847
TEST_TIME 110 8810
TEST_TIME 111 8808
TEST_TIME 112 8822
TEST_TIME 113 8839
TEST_TIME 114 8858
TEST_TIME 115 8847
TEST_TIME 116 8790
TEST_TIME 117 8741
TEST_TIME 118 8710
TEST_TIME 119 8687
TEST_TIME 120 8657

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-03-2021 07:28:50 to 08-03-2021 07:28:54.

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 1433175 1433175 0 0.00 0 24092 6 0 0
doc_list_biud 1433175 1433175 0 0.00 0 97457 32 0 0
sp_add_invoice_to_stock 4652 4551 101 2.17 33 176697 17910 0 101
sp_cancel_adding_invoice 5430 5430 0 0.00 7 95868 819 0 0
sp_cancel_client_order 1637 1637 0 0.00 5 8024 315 0 0
sp_cancel_customer_reserve 532951 532951 0 0.00 1 40168 70 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 3 13476 149 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 6044 77 0 0
sp_cancel_supplier_invoice 6027 6023 4 0.06 3 97467 862 0 4
sp_cancel_supplier_order 376 376 0 0.00 14 986919 122961 0 0
sp_cancel_write_off 10087 10087 0 0.00 1 26630 117 0 0
sp_client_order 18721 18721 0 0.00 4 112316 396 0 0
sp_customer_reserve 425601 425601 0 0.00 0 111778 173 0 0
sp_fill_shopping_cart 28529 28529 0 0.00 1 27120 49 0 0
sp_fill_shopping_cart_clo_res 425598 425598 0 0.00 0 23060 18 0 0
sp_get_clo_for_invoice 4546 4546 0 0.00 0 50554 465 0 0
sp_kill_cost_storno 16573 16573 0 0.00 0 9589 55 0 0
sp_kill_qstorno_ret_qs2qd 1082388 1082388 0 0.00 0 97455 37 0 0
sp_kill_qty_storno 570231 570231 0 0.00 0 97456 80 0 0
sp_lock_selected_doc 19189 19189 0 0.00 0 8677 6 0 0
sp_make_cost_storno 25472 25472 0 0.00 0 14432 51 0 0
sp_multiply_rows_for_pdistr 25472 25472 0 0.00 0 9930 35 0 0
sp_multiply_rows_for_qdistr 18721 18721 0 0.00 0 112305 224 0 0
sp_pay_from_customer 6481 6481 0 0.00 4 12023 110 0 0
sp_pay_to_supplier 5264 5264 0 0.00 4 12238 116 0 0
sp_payment_common 11745 11745 0 0.00 1 9917 26 0 0
sp_qd_handle_on_cancel_clo 1637 1637 0 0.00 0 8017 293 0 0
sp_qd_handle_on_invoice_upd_sts 9976 9976 0 0.00 0 24082 359 0 0
sp_qd_handle_on_reserve_upd_sts 9181 9181 0 0.00 0 9450 49 0 0
sp_reserve_write_off 9181 9181 0 0.00 7 13648 187 0 0
sp_supplier_invoice 4944 4944 0 0.00 7 124897 3768 0 0
sp_supplier_order 4875 4875 0 0.00 6 109797 1339 0 0
srv_aggregate_perf_data 96 96 0 0.00 2851 150951 16500 0 0
srv_fill_mon_cache_memory 58 58 0 0.00 1791 161641 50363 0 0
srv_find_qd_qs_mism 995308 995308 0 0.00 0 24091 5 0 0
srv_make_invnt_saldo 384 384 0 0.00 58 142881 3805 0 0
srv_make_money_saldo 671 671 0 0.00 1 15953 587 0 0
srv_recalc_idx_stat 4 4 0 0.00 82599 524965 322248 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4552 4552 0 0.00 0 24736 186 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 22821 137 0 0
t$perf-norm:sp_cancel_client_order 1637 1637 0 0.00 0 49764 49 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 4386 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 200 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 1544 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 870 16 0 0
t$perf-norm:sp_cancel_supplier_order 376 376 0 0.00 0 32409 1520 0 0
t$perf-norm:sp_cancel_write_off 938 938 0 0.00 0 610 4 0 0
t$perf-norm:sp_client_order 18721 18721 0 0.00 0 45222 22 0 0
t$perf-norm:sp_customer_reserve 892 892 0 0.00 0 2440 8 0 0
t$perf-norm:sp_pay_from_customer 6481 6481 0 0.00 0 33582 17 0 0
t$perf-norm:sp_pay_to_supplier 5264 5264 0 0.00 0 48101 20 0 0
t$perf-norm:sp_reserve_write_off 9181 9181 0 0.00 0 3720 9 0 0
t$perf-norm:sp_supplier_invoice 4944 4944 0 0.00 0 7684 18 0 0
t$perf-norm:sp_supplier_order 4875 4875 0 0.00 0 3776 17 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 191 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 58 58 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 384 384 0 0.00 0 1385 15 0 0
t$perf-norm:srv_make_money_saldo 671 671 0 0.00 0 2200 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 208 55 0 0
v_add_invoice_to_stock 9292 9090 202 2.17 0 7946 16 0 202
v_all_customers 33798 33798 0 0.00 0 62 1 0 0
v_all_suppliers 27430 27430 0 0.00 0 16 1 0 0
v_all_wares 37442 37442 0 0.00 0 137 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 3336 13 0 0
v_cancel_client_order 3274 3274 0 0.00 0 3115 5 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1932 6 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1542 5 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 2572 7 0 0
v_cancel_supplier_invoice 784 776 8 1.02 0 961 15 0 8
v_cancel_supplier_order 752 752 0 0.00 0 2177 6 0 0
v_cancel_write_off 1876 1876 0 0.00 0 3744 15 0 0
v_max_id_clo_ord 4875 4875 0 0.00 0 228 1 0 0
v_max_id_clo_res 892 892 0 0.00 0 7354 24 0 0
v_max_id_ord_sup 4933 4933 0 0.00 0 5954 6 0 0
v_max_non_paid_invoice 1357 1357 0 0.00 0 28 2 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 812 2 0 0
v_min_id_clo_ord 4875 4875 0 0.00 0 5248 10 0 0
v_min_id_clo_res 892 892 0 0.00 0 10311 22 0 0
v_min_id_ord_sup 4933 4933 0 0.00 0 9149 10 0 0
v_min_non_paid_invoice 5264 5264 0 0.00 0 9569 19 0 0
v_min_non_paid_realizn 6481 6481 0 0.00 0 9791 23 0 0
v_our_firm 9246 9246 0 0.00 0 23 1 0 0
v_reserve_write_off 18362 18362 0 0.00 0 4753 6 0 0
x_lock_depdocs_on_canc_invoice 11065 11065 0 0.00 0 21256 116 0 0
x_lock_depdocs_on_canc_sup_ord 376 376 0 0.00 0 9338 339 0 0
x_make_qstorno_1000_1200 4875 4875 0 0.00 2 109789 1247 0 0
x_make_qstorno_1200_2000 4944 4944 0 0.00 3 124858 3580 0 0
x_make_qstorno_2100_3300 425601 425601 0 0.00 0 111777 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-03-2021 07:28:55 to 08-03-2021 07:28:59.

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
04:56:59 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
04:58:00 26936 8192000000 9726087296 10024198144 803218592 8.929 100 88 176 0 833300800 8053232 22028976
04:59:36 20463 8192000000 9777303840 10088067072 851337040 9.413 100 87 173 2 883963632 12212944 20413648
05:01:01 24513 8192000000 9823030064 10143694848 880681808 9.706 100 91 182 0 923100048 21446352 20971888
05:02:35 6623 8192000000 9883938816 10217943040 921973200 10.116 100 82 164 2 991046912 51240080 17833632
05:03:47 5794 8192000000 9917579040 10264133632 961702736 10.506 100 76 153 1 1036872288 58915664 16253888
05:04:56 6577 8192000000 9982627600 10340048896 1003062592 10.908 100 90 180 2 1096624976 75426352 18136032
05:06:06 2774 8192000000 10004350720 10365583360 1028720112 11.156 100 85 170 1 1124150928 78165792 17265024
05:07:11 1718 8192000000 10037243328 10404040704 1058867408 11.446 100 87 174 0 1145720096 68883104 17969584
05:08:15 1620 8192000000 10046702784 10425417728 1074796432 11.598 100 65 130 2 1164467776 76462352 13208992
05:09:17 11145 8192000000 9993869968 10400129024 1077151280 11.620 100 28 55 5 1126959232 44117296 5690656
05:10:29 4374 8192000000 9995808784 10398711808 1090850464 11.751 100 33 63 1 1120862160 23438976 6572720
05:11:38 7235 8192000000 9988901008 10388869120 1088487344 11.728 100 31 63 2 1104083584 8847424 6748816
05:12:46 6996 8192000000 9997318688 10406735872 1094394720 11.784 100 17 34 1 1136363584 38328848 3640016
05:13:54 17538 8192000000 10005825968 10401656832 1095468032 11.795 100 64 127 2 1119526064 8688416 15369616
05:15:14 6391 8192000000 10002050880 10415562752 1087598464 11.720 100 41 82 1 1119112960 22694032 8820464
05:16:21 7601 8192000000 9996790432 10398756864 1080533056 11.653 98 21 41 0 1112501040 27196112 4771872
05:17:31 9025 8192000000 9986725648 10383941632 1056949216 11.427 97 22 44 1 1088140240 26285040 4905984
05:18:44 64373 8192000000 9958211472 10361438208 1008557696 10.961 96 36 74 0 1039724416 23570448 7596272
05:20:48 3534 8192000000 9921896768 10289893376 987641328 10.759 97 40 76 2 1006539648 9816208 9082112
05:21:55 8534 8192000000 9937970352 10330222592 1017358720 11.047 98 47 92 1 1044253520 16379056 10515744
05:23:09 7217 8192000000 9959000304 10351988736 1039172608 11.257 99 36 70 2 1060785936 13869696 7743632
05:24:19 30812 8192000000 9956743728 10349928448 1037417168 11.240 99 23 45 1 1060046512 17629936 4999408
05:25:57 8621 8192000000 9980064720 10369155072 1060687440 11.463 100 36 73 1 1094367552 25451680 8228432
05:27:11 3515 8192000000 10020449456 10413563904 1085491664 11.700 100 52 105 2 1122140800 24968560 11680576
05:28:24 46991 8192000000 10079338288 10493132800 1101522016 11.852 100 62 119 2 1179289648 64746992 13020640
05:30:42 30233 8192000000 10070767712 10483666944 1115337984 11.983 100 30 59 0 1184479248 62838976 6302288
05:32:18 10083 8192000000 10156870960 10567266304 1139960144 12.215 100 56 110 4 1271627664 118842208 12825312
05:33:33 6470 8192000000 10151685680 10577559552 1133697744 12.156 99 45 90 0 1254777392 111514336 9565312
05:34:42 19705 8192000000 10162912368 10592444416 1154406784 12.351 100 46 92 1 1280032800 115304592 10321424
05:36:09 4696 8192000000 10195562384 10614689792 1169480224 12.492 100 69 136 2 1302524144 118216832 14827088
05:37:18 5455 8192000000 10221018032 10634108928 1171651216 12.512 100 54 109 0 1323626048 140665712 11309120
05:38:27 5171 8192000000 10261598048 10676862976 1170263520 12.499 99 66 131 1 1354220304 169247120 14709664
05:39:38 4282 8192000000 10302343376 10708148224 1177021408 12.562 100 73 147 1 1399933120 207011520 15900192
05:40:49 1791 8192000000 10296624496 10697850880 1166386720 12.463 100 77 154 1 1395973440 212344368 17242352
05:41:57 2664 8192000000 10313280832 10716696576 1160182848 12.405 100 76 150 0 1408080880 230868832 17029200
05:43:06 9457 8192000000 10349358976 10753466368 1160878960 12.411 100 81 162 0 1444350240 265081200 18390080
05:44:20 2333 8192000000 10364700432 10801078272 1165018528 12.450 100 79 158 0 1480261568 295868624 19374416
05:45:29 5394 8192000000 10396962352 10809225216 1153741024 12.345 100 66 130 1 1513726816 344310736 15675056
05:46:42 6607 8192000000 10388033632 10824445952 1147601600 12.287 100 57 114 0 1497710400 335668544 14440256
05:47:53 12243 8192000000 10323120832 10763079680 1133593232 12.155 99 42 84 0 1424177184 279680624 10903328
05:49:07 10225 8192000000 10304964640 10773090304 1149807568 12.308 100 34 68 0 1438045232 278777280 9460384
05:50:20 20685 8192000000 10290016768 10720518144 1109911008 11.932 98 30 60 1 1379306592 261589136 7806448
05:51:42 14027 8192000000 10268936800 10729533440 1123173712 12.057 99 33 66 3 1376711872 245628000 7910160
05:53:01 32186 8192000000 10279833488 10736136192 1082282272 11.669 97 18 36 1 1373092736 286032256 4778208
05:54:34 58481 8192000000 10050846768 10568744960 1103869376 11.874 99 14 28 1 1340850224 233422384 3558464
05:56:45 141750 8192000000 10067341312 10512302080 1102666192 11.863 98 20 41 0 1153997696 46839328 4492176
06:00:13 161641 8192000000 10115352832 10588196864 1108266560 11.916 98 14 28 1 1186529520 75168016 3094944
06:04:47 98160 8192000000 10070035600 10516447232 1130848928 12.129 100 21 43 0 1167925472 32169872 4906672
06:07:26 68379 8192000000 10098727744 10551848960 1141182560 12.227 100 26 51 1 1211517392 64409984 5924848
06:10:05 58661 8192000000 10082858496 10589646848 1131656720 12.137 99 13 27 0 1221744720 87256848 2831152
06:12:09 22141 8192000000 10067860400 10510012416 1144466336 12.258 100 38 73 1 1171648544 19716352 7465856
06:13:33 58384 8192000000 10115829440 10552692736 1124057200 12.065 98 20 40 1 1188287904 60205136 4025568
06:15:33 72161 8192000000 10128295824 10605903872 1142132688 12.236 100 13 26 1 1193157504 48455808 2569008
06:17:50 72764 8192000000 10118624272 10552639488 1121350832 12.040 98 25 51 0 1202642768 76253968 5037968
06:20:58 105384 8192000000 10078548960 10561073152 1089451120 11.737 95 13 26 0 1133466480 41412304 2603056
06:25:46 58445 8192000000 10101892336 10572464128 1148926352 12.299 100 17 33 0 1204987840 52586400 3475088
06:27:44 59692 8192000000 10075027600 10528792576 1114220928 11.972 97 17 34 1 1165965792 48176080 3568784
06:29:49 69269 8192000000 10137545408 10597212160 1137460368 12.192 99 18 36 0 1224907664 83559152 3888144
06:31:59 66036 8192000000 10074554336 10517508096 1099840864 11.836 96 12 24 0 1122724128 20588064 2295200
06:34:26 25071 8192000000 10097595856 10528153600 1110956496 11.941 97 49 99 0 1156370480 35071856 10342128
06:35:52 67744 8192000000 10173721344 10654777344 1106825936 11.902 97 23 46 1 1219726320 108031440 4868944
06:38:00 102557 8192000000 10091516512 10572419072 1066234128 11.516 94 14 27 0 1162022672 92873360 2915184
06:40:43 114895 8192000000 10074412912 10594172928 1063516128 11.490 94 9 18 0 1145456368 80039808 1900432
06:45:44 105243 8192000000 10074212544 10530078720 1071643536 11.568 95 10 20 0 1125270560 51622320 2004704
06:48:32 28690 8192000000 10025528976 10471919616 1072044400 11.572 97 18 36 1 1093041008 17303536 3693072
06:50:01 93482 8192000000 10085661648 10527145984 1087512416 11.719 98 14 28 0 1140247520 49589648 3145456
06:52:35 32544 8192000000 10122982704 10559234048 1111332624 11.945 99 19 38 3 1206644288 90891392 4420272
06:54:08 38866 8192000000 10205699136 10648289280 1125302464 12.077 100 38 76 2 1263136128 128988672 8844992
06:55:48 88172 8192000000 10159235040 10661330944 1104218128 11.878 98 19 38 1 1250949824 142442752 4288944
06:58:48 43286 8192000000 10053988704 10491572224 1118146000 12.009 99 15 30 0 1143074784 22209296 2719488
07:00:34 14676 8192000000 10090132352 10503933952 1124087744 12.066 99 43 86 1 1150383232 16392368 9903120
07:01:49 33315 8192000000 10124562576 10551816192 1114867200 11.978 99 56 112 1 1192513520 65298848 12347472
07:03:26 84192 8192000000 10078461568 10525954048 1143950768 12.253 100 16 32 1 1188706880 41898672 2857440
07:05:51 113958 8192000000 10075145488 10530029568 1130734544 12.128 99 22 43 2 1175939760 40478416 4726800
07:10:00 78378 8192000000 10088793632 10579951616 1135677632 12.175 99 15 30 0 1191578128 52666816 3233680
07:12:23 82212 8192000000 10069862464 10523217920 1113887488 11.969 97 23 46 1 1169554768 50876608 4790672
07:14:46 60296 8192000000 10105622032 10601177088 1146232992 12.274 100 20 40 0 1213852272 63299376 4319904
07:16:52 47205 8192000000 10134487024 10572976128 1149021008 12.300 100 37 74 0 1206143360 49733712 7388640
07:18:48 95099 8192000000 10102278016 10594930688 1142760912 12.241 99 13 26 0 1218548656 73297184 2490560
07:21:33 88208 8192000000 10071923072 10527780864 1138812224 12.204 99 15 30 0 1172143456 30129504 3201728
07:24:03 57291 8192000000 10093750304 10570022912 1151904144 12.327 100 7 14 0 1196465856 43135968 1425744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-03-2021 07:29:01 to 08-03-2021 07:29:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-03-2021 07:29:16 to 08-03-2021 07:29:18.

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          223276
MON$OLDEST_ACTIVE               224475
MON$OLDEST_SNAPSHOT             224475
MON$NEXT_TRANSACTION            224475
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                       2077408
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                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2382 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2382 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.2382 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 ---
:199: 
:200: mscs-test-161.budzdorov.ru    Mon Mar  8 07:27:02 2021
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:202: 
:203: 
:204: mscs-test-161.budzdorov.ru    Mon Mar  8 07:27:09 2021
:205:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:206: 
--- end of diff output ---

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

08.03.21 07:29:19. 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-03-08 07:29:22 No severe PSQL-related problems occured
08.03.21 07:29:23. End of report.