Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.02.2021 10:27
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 16840344 kB
MemAvailable 31212928 kB
Buffers 0 kB
Cached 14820208 kB
SwapCached 4876 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-02-02 10:26:57
DTS_END                         2021-02-02 10:26:57.8770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59834
TRN_ID                          227443
ATT_ID                          614
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: 8586.38 26140 1030366
customer order: creation 164.43 388 19732
customer order: refuse 13.35 373 1602
order to supplier: creation 43.98 1393 5278
order to supplier: removal 3.29 122802 395
invoice (draft): creation 43.36 3715 5203
invoice (draft): removal 47.72 1110 5726
invoice accept: apply 39.78 17463 4773
invoice accept: cancel 42.54 911 5105
customer reserve: creation 3716.47 168 445976
customer reserve: removal 4197.42 74 503690
realization accept: apply 80.93 161 9712
realization accept: cancel 68.97 135 8276
payment from customer: creation 58.40 105 7008
payment from customer: removal 5.81 73 697
payment to supplier: creation 46.44 116 5573
payment to supplier: removal 3.17 81 380
service: total inventory turnovers 3.48 3948 417
service: total monetary turnovers 6.08 493 729
service: aggregate perf. data 0.75 20541 90
service: refresh index statistics 0.03 348753 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-02-2021 10:27:35 to 02-02-2021 10:27:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2084
TEST_TIME 2 2397
TEST_TIME 3 2693
TEST_TIME 4 3545
TEST_TIME 5 3645
TEST_TIME 6 4271
TEST_TIME 7 4163
TEST_TIME 8 4268
TEST_TIME 9 4364
TEST_TIME 10 4865
TEST_TIME 11 4836
TEST_TIME 12 4973
TEST_TIME 13 5517
TEST_TIME 14 5533
TEST_TIME 15 5885
TEST_TIME 16 6053
TEST_TIME 17 6209
TEST_TIME 18 6335
TEST_TIME 19 6396
TEST_TIME 20 7071
TEST_TIME 21 7213
TEST_TIME 22 7618
TEST_TIME 23 7630
TEST_TIME 24 7949
TEST_TIME 25 8341
TEST_TIME 26 8383
TEST_TIME 27 8800
TEST_TIME 28 9663
TEST_TIME 29 10274
TEST_TIME 30 10132
TEST_TIME 31 9924
TEST_TIME 32 9938
TEST_TIME 33 9698
TEST_TIME 34 9710
TEST_TIME 35 9643
TEST_TIME 36 9674
TEST_TIME 37 9574
TEST_TIME 38 9439
TEST_TIME 39 9351
TEST_TIME 40 9369
TEST_TIME 41 9168
TEST_TIME 42 9071
TEST_TIME 43 9224
TEST_TIME 44 9071
TEST_TIME 45 8970
TEST_TIME 46 8880
TEST_TIME 47 8863
TEST_TIME 48 8745
TEST_TIME 49 8683
TEST_TIME 50 8680
TEST_TIME 51 8677
TEST_TIME 52 8801
TEST_TIME 53 8753
TEST_TIME 54 8834
TEST_TIME 55 8756
TEST_TIME 56 8739
TEST_TIME 57 8828
TEST_TIME 58 8802
TEST_TIME 59 8911
TEST_TIME 60 8897
TEST_TIME 61 8924
TEST_TIME 62 8971
TEST_TIME 63 8956
TEST_TIME 64 8960
TEST_TIME 65 9020
TEST_TIME 66 9023
TEST_TIME 67 8893
TEST_TIME 68 8796
TEST_TIME 69 8746
TEST_TIME 70 8651
TEST_TIME 71 8591
TEST_TIME 72 8616
TEST_TIME 73 8614
TEST_TIME 74 8598
TEST_TIME 75 8608
TEST_TIME 76 8611
TEST_TIME 77 8626
TEST_TIME 78 8661
TEST_TIME 79 8717
TEST_TIME 80 8683
TEST_TIME 81 8698
TEST_TIME 82 8693
TEST_TIME 83 8690
TEST_TIME 84 8720
TEST_TIME 85 8636
TEST_TIME 86 8544
TEST_TIME 87 8546
TEST_TIME 88 8492
TEST_TIME 89 8528
TEST_TIME 90 8505
TEST_TIME 91 8521
TEST_TIME 92 8503
TEST_TIME 93 8494
TEST_TIME 94 8492
TEST_TIME 95 8569
TEST_TIME 96 8559
TEST_TIME 97 8545
TEST_TIME 98 8622
TEST_TIME 99 8604
TEST_TIME 100 8629
TEST_TIME 101 8696
TEST_TIME 102 8663
TEST_TIME 103 8703
TEST_TIME 104 8719
TEST_TIME 105 8663
TEST_TIME 106 8610
TEST_TIME 107 8587
TEST_TIME 108 8530
TEST_TIME 109 8537
TEST_TIME 110 8561
TEST_TIME 111 8547
TEST_TIME 112 8526
TEST_TIME 113 8574
TEST_TIME 114 8554
TEST_TIME 115 8535
TEST_TIME 116 8547
TEST_TIME 117 8580
TEST_TIME 118 8565
TEST_TIME 119 8593
TEST_TIME 120 8589

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-02-2021 10:27:39 to 02-02-2021 10:27:43.

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 1442531 1442531 0 0.00 0 31515 6 0 0
doc_list_biud 1442530 1442530 0 0.00 0 52596 33 0 0
sp_add_invoice_to_stock 4929 4773 156 3.16 39 150537 17463 0 156
sp_cancel_adding_invoice 5105 5105 0 0.00 5 95165 911 0 0
sp_cancel_client_order 1602 1602 0 0.00 4 17882 374 0 0
sp_cancel_customer_reserve 503690 503690 0 0.00 1 52598 74 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 3204 74 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 4531 81 0 0
sp_cancel_supplier_invoice 5731 5726 5 0.08 2 32800 1111 0 5
sp_cancel_supplier_order 395 395 0 0.00 5 1052304 122803 0 0
sp_cancel_write_off 8276 8276 0 0.00 1 9100 135 0 0
sp_client_order 19732 19732 0 0.00 4 30990 388 0 0
sp_customer_reserve 445976 445976 0 0.00 0 57566 169 0 0
sp_fill_shopping_cart 30209 30209 0 0.00 1 44318 47 0 0
sp_fill_shopping_cart_clo_res 445972 445972 0 0.00 0 25085 19 0 0
sp_get_clo_for_invoice 4769 4769 0 0.00 0 50192 438 0 0
sp_kill_cost_storno 14458 14458 0 0.00 0 31501 74 0 0
sp_kill_qstorno_ret_qs2qd 1021777 1021777 0 0.00 0 49250 41 0 0
sp_kill_qty_storno 539275 539275 0 0.00 0 52596 87 0 0
sp_lock_selected_doc 19854 19854 0 0.00 0 7474 4 0 0
sp_make_cost_storno 27062 27062 0 0.00 0 30033 50 0 0
sp_multiply_rows_for_pdistr 27062 27062 0 0.00 0 7247 30 0 0
sp_multiply_rows_for_qdistr 19732 19732 0 0.00 0 30979 210 0 0
sp_pay_from_customer 7008 7008 0 0.00 4 9079 106 0 0
sp_pay_to_supplier 5573 5573 0 0.00 4 8758 117 0 0
sp_payment_common 12581 12581 0 0.00 1 6863 18 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 17875 331 0 0
sp_qd_handle_on_invoice_upd_sts 9874 9874 0 0.00 0 18198 396 0 0
sp_qd_handle_on_reserve_upd_sts 9712 9712 0 0.00 0 12347 46 0 0
sp_reserve_write_off 9712 9712 0 0.00 7 12428 162 0 0
sp_supplier_invoice 5203 5203 0 0.00 7 123339 3716 0 0
sp_supplier_order 5278 5278 0 0.00 6 73775 1393 0 0
srv_aggregate_perf_data 90 90 0 0.00 2805 398003 20542 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1641 157427 47774 0 0
srv_find_qd_qs_mism 984076 984076 0 0.00 0 14201 5 0 0
srv_make_invnt_saldo 417 417 0 0.00 55 162914 3949 0 0
srv_make_money_saldo 729 729 0 0.00 1 17352 493 0 0
srv_recalc_idx_stat 4 4 0 0.00 200631 578150 348754 0 0
t$perf-abend:sp_add_invoice_to_stock 156 156 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4774 4774 0 0.00 0 9205 143 0 0
t$perf-norm:sp_cancel_adding_invoice 353 353 0 0.00 0 13315 109 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 1338 11 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 5488 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 892 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 562 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 358 358 0 0.00 0 1214 12 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 26600 1699 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 2625 8 0 0
t$perf-norm:sp_client_order 19732 19732 0 0.00 0 10714 18 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1391 6 0 0
t$perf-norm:sp_pay_from_customer 7008 7008 0 0.00 0 3099 7 0 0
t$perf-norm:sp_pay_to_supplier 5573 5573 0 0.00 0 10713 11 0 0
t$perf-norm:sp_reserve_write_off 9712 9712 0 0.00 0 7465 9 0 0
t$perf-norm:sp_supplier_invoice 5203 5203 0 0.00 0 2113 14 0 0
t$perf-norm:sp_supplier_order 5278 5278 0 0.00 0 12363 24 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 107 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 194 6 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 493 6 0 0
t$perf-norm:srv_make_money_saldo 729 729 0 0.00 0 318 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9848 9536 312 3.16 0 4704 19 0 312
v_all_customers 36246 36246 0 0.00 0 21 1 0 0
v_all_suppliers 29620 29620 0 0.00 0 16 1 0 0
v_all_wares 39464 39464 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 706 706 0 0.00 0 912 7 0 0
v_cancel_client_order 3204 3204 0 0.00 0 10326 13 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3186 8 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 10387 13 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 721 4 0 0
v_cancel_supplier_invoice 726 716 10 1.37 0 771 14 0 10
v_cancel_supplier_order 790 790 0 0.00 0 701 5 0 0
v_cancel_write_off 1848 1848 0 0.00 0 3745 15 0 0
v_max_id_clo_ord 5278 5278 0 0.00 0 6099 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 21662 33 0 0
v_max_id_ord_sup 5199 5199 0 0.00 0 4890 8 0 0
v_max_non_paid_invoice 1240 1240 0 0.00 0 575 2 0 0
v_max_non_paid_realizn 3732 3732 0 0.00 0 1639 3 0 0
v_min_id_clo_ord 5278 5278 0 0.00 0 4488 9 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 3432 8 0 0
v_min_id_ord_sup 5199 5199 0 0.00 0 7150 10 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 6453 10 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 6859 14 0 0
v_our_firm 9770 9770 0 0.00 0 21 1 0 0
v_reserve_write_off 19424 19424 0 0.00 0 8414 7 0 0
x_lock_depdocs_on_canc_invoice 10473 10473 0 0.00 0 25027 126 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 8076 410 0 0
x_make_qstorno_1000_1200 5278 5278 0 0.00 2 73753 1311 0 0
x_make_qstorno_1200_2000 5203 5203 0 0.00 3 123145 3527 0 0
x_make_qstorno_2100_3300 445976 445976 0 0.00 0 54827 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-02-2021 10:27:44 to 02-02-2021 10:27: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
07:57:01 2 8192000000 8796640128 8807108608 3168304 0.038 1 1 2 0 3265920 61840 35776
07:58:03 27841 8192000000 9728223408 10026876928 805385984 8.951 100 85 170 1 832807888 7665792 19756112
07:59:36 12932 8192000000 9768213472 10073874432 842309296 9.323 100 90 181 0 876729072 13124480 21295296
08:00:54 15883 8192000000 9791524640 10104885248 860830192 9.508 100 86 172 0 900456112 19889312 19736608
08:02:16 14591 8192000000 9852420928 10177953792 894905472 9.848 100 79 156 1 956129904 43606464 17617968
08:03:34 11010 8192000000 9901978752 10244100096 945903136 10.351 100 79 158 1 1018673664 55989376 16781152
08:04:54 4761 8192000000 9939300992 10289963008 981923696 10.703 100 75 151 0 1048151488 50561616 15666176
08:06:01 5358 8192000000 9991242320 10346463232 1013629984 11.010 100 92 183 1 1106024736 74298336 18096416
08:07:10 2079 8192000000 9991924560 10359717888 1034291232 11.210 100 86 169 0 1107811680 56144800 17375648
08:08:14 1606 8192000000 10066244336 10436796416 1059418640 11.451 100 94 186 2 1180613552 102109328 19085584
08:09:16 28863 8192000000 9989173840 10372026368 1068288800 11.536 100 59 116 2 1103046112 22368336 12388976
08:10:46 3820 8192000000 10014660736 10404683776 1074118992 11.591 100 47 94 0 1124426848 40529872 9777984
08:11:53 5028 8192000000 10007207392 10396340224 1079963776 11.647 100 50 100 1 1112563680 21986384 10613520
08:12:59 8836 8192000000 10009052560 10409840640 1083795504 11.684 100 25 51 0 1124431632 35349632 5286496
08:14:10 68168 8192000000 10001462816 10408837120 1087177392 11.716 100 32 64 1 1110653056 16600192 6875472
08:16:22 4388 8192000000 9991924640 10393284608 1086824608 11.712 100 31 62 2 1122861968 29535648 6501712
08:17:27 5536 8192000000 10000100144 10405756928 1088779648 11.731 100 37 72 2 1124531552 27457152 8294752
08:18:34 3969 8192000000 9980769552 10367082496 1058757232 11.445 98 24 49 1 1083738000 19784400 5196368
08:19:42 46742 8192000000 9927106672 10314866688 910915552 10.006 85 34 68 0 933851648 15308864 7627232
08:21:29 4589 8192000000 9949778448 10332004352 1004307920 10.920 96 55 113 1 1035705424 19738752 11658752
08:22:37 9643 8192000000 9918674048 10301554688 981439568 10.698 96 34 68 0 1003273152 13952224 7881360
08:23:52 9284 8192000000 9950071408 10342981632 1024922272 11.120 98 38 73 5 1049506304 15252016 9332016
08:25:03 26662 8192000000 9970578384 10372116480 1049767904 11.358 100 38 76 0 1077775344 19224384 8783056
08:26:35 5502 8192000000 9989839952 10387476480 1061185232 11.468 100 56 111 1 1095428864 21845424 12398208
08:27:46 15998 8192000000 10022663376 10410295296 1088698752 11.730 100 50 100 2 1123866016 23924912 11242352
08:29:08 67258 8192000000 10089749216 10512896000 1111737232 11.949 100 39 77 5 1202422992 82533744 8152016
08:31:21 54716 8192000000 10123271760 10539540480 1128849392 12.111 100 36 72 1 1234267712 97352144 8066176
08:33:29 2622 8192000000 10147973104 10554494976 1141520176 12.230 100 46 88 0 1258861312 108946544 8394592
08:34:36 3722 8192000000 10156362832 10565926912 1145699616 12.269 100 38 74 1 1274640784 120523536 8417632
08:35:47 12197 8192000000 10213205552 10638725120 1157737168 12.382 100 54 106 3 1317297952 147181808 12378976
08:37:06 5462 8192000000 10239261248 10654396416 1171220272 12.508 100 61 120 0 1343389904 158038784 14130848
08:38:19 5383 8192000000 10282959168 10695671808 1176879264 12.561 100 63 125 0 1388337856 196735072 14723520
08:39:31 3432 8192000000 10330457184 10753339392 1187709120 12.662 100 71 138 0 1431334272 227712672 15912480
08:40:38 15581 8192000000 10317176080 10742865920 1172465248 12.520 98 51 102 0 1418518784 233507856 12545680
08:41:59 2635 8192000000 10349406336 10759495680 1192647616 12.708 100 76 150 1 1452932672 243137504 17147552
08:43:07 2087 8192000000 10393483664 10801491968 1185816608 12.644 100 76 152 1 1494393120 290753312 17823200
08:44:14 2177 8192000000 10442602656 10849443840 1180141648 12.592 99 87 172 0 1528885968 329170992 19573328
08:45:22 1910 8192000000 10470111296 10874761216 1190328496 12.686 100 92 185 0 1563128432 351576224 21223712
08:46:33 1640 8192000000 10442540576 10914877440 1168676240 12.484 100 90 178 2 1534145552 345041488 20427824
08:47:40 1894 8192000000 10405724784 10826665984 1152933168 12.337 99 62 126 4 1507217264 339428512 14855584
08:48:48 6558 8192000000 10394395008 10834567168 1166475072 12.464 100 41 82 2 1489552560 312794976 10282512
08:50:02 17239 8192000000 10366585248 10847121408 1125194320 12.076 97 33 65 1 1464304848 330833664 8276864
08:51:23 26259 8192000000 10341848080 10784047104 1098572192 11.824 97 27 54 0 1432050160 326039904 7438064
08:52:52 12180 8192000000 10345822080 10801668096 1108498640 11.918 100 25 50 1 1456513200 340762832 7251728
08:54:07 8775 8192000000 10317280032 10780184576 1122257536 12.048 100 28 56 1 1431042880 302011584 6773760
08:55:18 60001 8192000000 10097631744 10592043008 1131701760 12.137 100 14 28 1 1211213984 76016224 3496000
08:57:19 13179 8192000000 10098126224 10523406336 1110920080 11.941 98 33 66 2 1188089056 70321424 6847552
08:58:36 46260 8192000000 10089244320 10517401600 1126446480 12.088 99 30 59 1 1178531472 46351216 5733776
09:00:47 83457 8192000000 10089210592 10549370880 1081528096 11.662 95 15 30 0 1176926864 92206480 3192288
09:03:16 65398 8192000000 10066436736 10515120128 1083545024 11.681 96 17 34 2 1127321744 40367680 3409040
09:05:22 52997 8192000000 10104388672 10559602688 1105229008 11.887 99 21 42 1 1194072144 84434240 4408896
09:07:41 84853 8192000000 10076733728 10569224192 1120456208 12.031 100 19 38 0 1225846576 100952128 4438240
09:11:08 93796 8192000000 10121842784 10590609408 1126285648 12.086 99 24 48 0 1199409104 67525088 5598368
09:14:03 95044 8192000000 10116466832 10586841088 1121014352 12.037 98 15 30 1 1209841648 85376848 3450448
09:17:14 75368 8192000000 10076495280 10618535936 1141738416 12.232 100 9 18 0 1258136352 114264912 2133024
09:19:39 62832 8192000000 10106710640 10559602688 1118569936 12.013 98 30 60 2 1177150640 52470064 6110640
09:21:50 11686 8192000000 10086211232 10510934016 1128384352 12.106 99 59 115 1 1176201888 35966080 11851456
09:23:08 64322 8192000000 10059602048 10500149248 1112560048 11.957 99 22 44 1 1142473600 25763216 4150336
09:25:12 124391 8192000000 10066896064 10526580736 1114661824 11.977 98 10 20 0 1154407584 37884352 1861408
09:28:22 42239 8192000000 10069741392 10534363136 1127896144 12.102 99 12 24 0 1159724672 29738832 2089696
09:30:11 32177 8192000000 10110261200 10536968192 1138440528 12.201 99 23 45 0 1186700512 43407040 4852944
09:31:57 100477 8192000000 10087378512 10584137728 1142513264 12.239 100 15 30 0 1200785280 55352496 2919520
09:34:41 80338 8192000000 10072517856 10518872064 1120637040 12.033 98 17 35 0 1168196992 43662576 3897376
09:37:01 62760 8192000000 10102477936 10579165184 1143871728 12.252 100 14 28 0 1200016576 53428656 2716192
09:39:04 65149 8192000000 10090095856 10551529472 1129038576 12.112 98 12 23 1 1171301040 39661168 2601296
09:41:12 58763 8192000000 10063312528 10516033536 1083037056 11.676 95 27 54 0 1116227168 27450992 5739120
09:43:12 74159 8192000000 10089315088 10520985600 1088501856 11.728 95 19 38 2 1152456128 59488208 4466064
09:45:26 81891 8192000000 10060879040 10490540032 1052303792 11.383 93 9 18 0 1085869392 31382704 2182896
09:47:54 56859 8192000000 10064541072 10504622080 1104738048 11.883 98 20 41 1 1149246464 40556048 3952368
09:49:51 25371 8192000000 10059232432 10485899264 1075816224 11.608 96 49 98 1 1110895424 25510800 9568400
09:51:20 157427 8192000000 10064803856 10541903872 1082519856 11.671 97 22 45 0 1153249424 65995104 4734464
09:55:45 81996 8192000000 10055264672 10485202944 1083356096 11.679 97 18 36 1 1137600896 50924576 3320224
09:58:16 27851 8192000000 10062106816 10486239232 1096864640 11.808 98 40 80 1 1129440672 23519616 9056416
09:59:45 82098 8192000000 10079902528 10549186560 1108531280 11.919 98 22 44 0 1175799456 62959664 4308512
10:02:09 48391 8192000000 10066573072 10487259136 1120994000 12.036 100 14 28 1 1155461728 31934240 2533488
10:03:57 48902 8192000000 10066690176 10559909888 1095819552 11.798 97 24 48 2 1145238016 44244496 5173968
10:05:48 60408 8192000000 10143258480 10606342144 1124563552 12.070 99 28 56 1 1225278800 94757952 5957296
10:07:50 69111 8192000000 10098458144 10524188672 1128945856 12.111 99 20 38 1 1153915984 21212448 3757680
10:10:03 58969 8192000000 10099939440 10547429376 1110656304 11.939 97 25 50 1 1168336016 51796256 5883456
10:12:06 115598 8192000000 10102213680 10562379776 1101183312 11.849 97 25 50 1 1175290000 68281920 5824768
10:16:00 66129 8192000000 10049238400 10501836800 1111862112 11.950 98 19 38 2 1153723712 37736848 4124752
10:18:06 49515 8192000000 10087699840 10523774976 1129269472 12.114 99 19 38 1 1164554208 31687168 3597568
10:20:00 38749 8192000000 10071154528 10511933440 1105601072 11.891 97 23 46 2 1132670512 22136560 4932880
10:21:50 52491 8192000000 10137827040 10609233920 1141420048 12.229 100 41 82 2 1207320592 57360576 8539968
10:23:57 57177 8192000000 10111274992 10542583808 1108034480 11.914 97 24 48 1 1162701760 49962400 4704880
10:26:04 63613 8192000000 8901955424 9255657472 14766912 0.179 2 2 4 0 22171136 7073104 331120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-02-2021 10:27:50 to 02-02-2021 10:28:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 156 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-02-2021 10:28:05 to 02-02-2021 10:28:07.

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          227945
MON$OLDEST_ACTIVE               227946
MON$OLDEST_SNAPSHOT             227946
MON$NEXT_TRANSACTION            227946
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                       2072224
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2357 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2357 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.2357 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 ---
--- end of diff output ---

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

02.02.21 10:28:08. 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-02-02 10:28:11 No severe PSQL-related problems occured
02.02.21 10:28:12. End of report.