Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.09.2021 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16138764 kB
MemAvailable 30799808 kB
Buffers 20 kB
Cached 15542180 kB
SwapCached 668 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.1
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-09-22 04:37:09
DTS_END                         2021-09-22 04:37:09.3950
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/34230
TRN_ID                          227173
ATT_ID                          635
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: 8598.69 23225 1031843
customer order: creation 165.35 399 19842
customer order: refuse 13.85 353 1662
order to supplier: creation 43.63 1517 5235
order to supplier: removal 3.30 108680 396
invoice (draft): creation 44.15 4032 5298
invoice (draft): removal 47.95 923 5754
invoice accept: apply 39.88 21857 4786
invoice accept: cancel 43.99 930 5279
customer reserve: creation 3648.48 219 437817
customer reserve: removal 4262.11 66 511453
realization accept: apply 79.95 181 9594
realization accept: cancel 81.74 121 9809
payment from customer: creation 57.09 111 6851
payment from customer: removal 5.58 87 670
payment to supplier: creation 46.98 132 5637
payment to supplier: removal 3.48 84 418
service: total inventory turnovers 3.89 3588 467
service: total monetary turnovers 6.28 485 753
service: aggregate perf. data 0.98 15603 118
service: refresh index statistics 0.03 305141 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-09-2021 04:39:11 to 22-09-2021 04:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6679
TEST_TIME 2 6004
TEST_TIME 3 5488
TEST_TIME 4 4986
TEST_TIME 5 4923
TEST_TIME 6 5090
TEST_TIME 7 4783
TEST_TIME 8 4972
TEST_TIME 9 5118
TEST_TIME 10 5136
TEST_TIME 11 5470
TEST_TIME 12 5570
TEST_TIME 13 5702
TEST_TIME 14 5920
TEST_TIME 15 6375
TEST_TIME 16 6570
TEST_TIME 17 7021
TEST_TIME 18 6946
TEST_TIME 19 7422
TEST_TIME 20 7904
TEST_TIME 21 7925
TEST_TIME 22 8245
TEST_TIME 23 8762
TEST_TIME 24 9185
TEST_TIME 25 9422
TEST_TIME 26 9789
TEST_TIME 27 10208
TEST_TIME 28 9949
TEST_TIME 29 9691
TEST_TIME 30 9601
TEST_TIME 31 9505
TEST_TIME 32 9461
TEST_TIME 33 9568
TEST_TIME 34 9463
TEST_TIME 35 9468
TEST_TIME 36 9315
TEST_TIME 37 9289
TEST_TIME 38 9115
TEST_TIME 39 9115
TEST_TIME 40 9032
TEST_TIME 41 8952
TEST_TIME 42 8890
TEST_TIME 43 8753
TEST_TIME 44 8744
TEST_TIME 45 8842
TEST_TIME 46 8774
TEST_TIME 47 8705
TEST_TIME 48 8790
TEST_TIME 49 8854
TEST_TIME 50 8804
TEST_TIME 51 8864
TEST_TIME 52 8872
TEST_TIME 53 8877
TEST_TIME 54 8912
TEST_TIME 55 8971
TEST_TIME 56 8962
TEST_TIME 57 8901
TEST_TIME 58 8778
TEST_TIME 59 8748
TEST_TIME 60 8696
TEST_TIME 61 8614
TEST_TIME 62 8628
TEST_TIME 63 8714
TEST_TIME 64 8722
TEST_TIME 65 8754
TEST_TIME 66 8796
TEST_TIME 67 8752
TEST_TIME 68 8696
TEST_TIME 69 8751
TEST_TIME 70 8758
TEST_TIME 71 8772
TEST_TIME 72 8800
TEST_TIME 73 8830
TEST_TIME 74 8791
TEST_TIME 75 8847
TEST_TIME 76 8899
TEST_TIME 77 8790
TEST_TIME 78 8795
TEST_TIME 79 8774
TEST_TIME 80 8750
TEST_TIME 81 8668
TEST_TIME 82 8625
TEST_TIME 83 8660
TEST_TIME 84 8708
TEST_TIME 85 8675
TEST_TIME 86 8685
TEST_TIME 87 8695
TEST_TIME 88 8659
TEST_TIME 89 8640
TEST_TIME 90 8691
TEST_TIME 91 8738
TEST_TIME 92 8732
TEST_TIME 93 8871
TEST_TIME 94 8852
TEST_TIME 95 8873
TEST_TIME 96 8862
TEST_TIME 97 8879
TEST_TIME 98 8842
TEST_TIME 99 8780
TEST_TIME 100 8742
TEST_TIME 101 8731
TEST_TIME 102 8775
TEST_TIME 103 8761
TEST_TIME 104 8724
TEST_TIME 105 8722
TEST_TIME 106 8723
TEST_TIME 107 8690
TEST_TIME 108 8680
TEST_TIME 109 8719
TEST_TIME 110 8762
TEST_TIME 111 8735
TEST_TIME 112 8748
TEST_TIME 113 8744
TEST_TIME 114 8767
TEST_TIME 115 8795
TEST_TIME 116 8746
TEST_TIME 117 8724
TEST_TIME 118 8690
TEST_TIME 119 8675
TEST_TIME 120 8606

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-09-2021 04:39:14 to 22-09-2021 04:39:18.

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 1436580 1436580 0 0.00 0 16929 8 0 0
doc_list_biud 1436577 1436577 0 0.00 0 77859 30 0 0
sp_add_invoice_to_stock 4942 4786 156 3.15 51 237436 21857 0 156
sp_cancel_adding_invoice 5279 5279 0 0.00 6 87509 931 0 0
sp_cancel_client_order 1662 1662 0 0.00 4 8439 354 0 0
sp_cancel_customer_reserve 511453 511453 0 0.00 1 77860 67 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 2168 87 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 3300 84 0 0
sp_cancel_supplier_invoice 5767 5754 13 0.22 3 20310 924 0 13
sp_cancel_supplier_order 396 396 0 0.00 10 1161878 108681 0 0
sp_cancel_write_off 9809 9809 0 0.00 1 11325 122 0 0
sp_client_order 19842 19842 0 0.00 4 50026 400 0 0
sp_customer_reserve 437817 437817 0 0.00 0 151801 220 0 0
sp_fill_shopping_cart 30364 30364 0 0.00 1 16511 48 0 0
sp_fill_shopping_cart_clo_res 437806 437806 0 0.00 0 16497 17 0 0
sp_get_clo_for_invoice 4772 4772 0 0.00 0 23894 417 0 0
sp_kill_cost_storno 16176 16176 0 0.00 0 9448 62 0 0
sp_kill_qstorno_ret_qs2qd 1038865 1038865 0 0.00 0 77846 36 0 0
sp_kill_qty_storno 548719 548719 0 0.00 0 77858 78 0 0
sp_lock_selected_doc 19912 19912 0 0.00 0 9219 4 0 0
sp_make_cost_storno 26854 26854 0 0.00 0 16322 54 0 0
sp_multiply_rows_for_pdistr 26854 26854 0 0.00 0 9682 34 0 0
sp_multiply_rows_for_qdistr 19841 19841 0 0.00 0 32720 217 0 0
sp_pay_from_customer 6851 6851 0 0.00 4 13682 112 0 0
sp_pay_to_supplier 5637 5637 0 0.00 5 25427 133 0 0
sp_payment_common 12488 12488 0 0.00 1 8843 21 0 0
sp_qd_handle_on_cancel_clo 1662 1662 0 0.00 0 8430 329 0 0
sp_qd_handle_on_invoice_upd_sts 10051 10051 0 0.00 0 33894 380 0 0
sp_qd_handle_on_reserve_upd_sts 9594 9594 0 0.00 0 14524 54 0 0
sp_reserve_write_off 9594 9594 0 0.00 7 16619 182 0 0
sp_supplier_invoice 5298 5298 0 0.00 7 99407 4032 0 0
sp_supplier_order 5235 5235 0 0.00 5 82764 1517 0 0
srv_aggregate_perf_data 118 118 0 0.00 2685 187377 15603 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1455 183524 49138 0 0
srv_find_qd_qs_mism 985871 985871 0 0.00 0 13753 7 0 0
srv_make_invnt_saldo 467 467 0 0.00 61 115867 3589 0 0
srv_make_money_saldo 753 753 0 0.00 1 17490 485 0 0
srv_recalc_idx_stat 4 4 0 0.00 177626 411821 305141 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 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4786 4786 0 0.00 0 11630 190 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 4664 92 0 0
t$perf-norm:sp_cancel_client_order 1662 1662 0 0.00 0 9904 25 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 650 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 657 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1147 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 344 344 0 0.00 0 774 16 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 24091 1598 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 584 4 0 0
t$perf-norm:sp_client_order 19842 19842 0 0.00 0 9842 20 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 459 4 0 0
t$perf-norm:sp_pay_from_customer 6851 6851 0 0.00 0 9308 12 0 0
t$perf-norm:sp_pay_to_supplier 5637 5637 0 0.00 0 7287 13 0 0
t$perf-norm:sp_reserve_write_off 9594 9594 0 0.00 0 11552 12 0 0
t$perf-norm:sp_supplier_invoice 5298 5298 0 0.00 0 7396 22 0 0
t$perf-norm:sp_supplier_order 5235 5235 0 0.00 0 4094 15 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 805 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 69 2 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 2678 14 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 591 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 11 4 0 0
v_add_invoice_to_stock 9852 9540 312 3.16 0 4016 18 0 312
v_all_customers 36204 36204 0 0.00 0 24 1 0 0
v_all_suppliers 29020 29020 0 0.00 0 211 1 0 0
v_all_wares 39682 39682 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2703 10 0 0
v_cancel_client_order 3324 3324 0 0.00 0 1870 4 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1784 7 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 1569 6 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 679 6 0 0
v_cancel_supplier_invoice 714 688 26 3.64 0 3393 29 0 26
v_cancel_supplier_order 792 792 0 0.00 0 1856 8 0 0
v_cancel_write_off 1858 1858 0 0.00 0 2754 14 0 0
v_max_id_clo_ord 5233 5233 0 0.00 0 334 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 4615 15 0 0
v_max_id_ord_sup 5290 5290 0 0.00 0 7274 6 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 2969 4 0 0
v_max_non_paid_realizn 3762 3762 0 0.00 0 1142 3 0 0
v_min_id_clo_ord 5233 5233 0 0.00 0 7935 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 2779 16 0 0
v_min_id_ord_sup 5290 5290 0 0.00 0 15468 11 0 0
v_min_non_paid_invoice 5637 5637 0 0.00 0 8598 14 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 8836 15 0 0
v_our_firm 9656 9656 0 0.00 0 108 1 0 0
v_reserve_write_off 19188 19188 0 0.00 0 4368 6 0 0
x_lock_depdocs_on_canc_invoice 10689 10689 0 0.00 0 26808 112 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 9360 349 0 0
x_make_qstorno_1000_1200 5235 5235 0 0.00 2 82740 1433 0 0
x_make_qstorno_1200_2000 5298 5298 0 0.00 2 99367 3841 0 0
x_make_qstorno_2100_3300 437817 437817 0 0.00 0 151799 199 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-09-2021 04:39:19 to 22-09-2021 04:39:23.

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
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:13 26471 8192000000 9704295824 9998020608 780508192 8.698 100 82 164 1 807907264 7546256 19852816
02:09:48 14433 8192000000 9751464448 10055135232 826873840 9.168 100 88 176 0 860549936 12075104 21600992
02:11:06 13831 8192000000 9795556384 10111229952 870854672 9.609 100 85 170 0 908219664 17773920 19591072
02:12:30 13289 8192000000 9862751760 10195468288 916220752 10.059 100 85 169 0 977569472 43164336 18184384
02:13:52 11415 8192000000 9923471120 10264526848 946426384 10.356 100 85 169 0 1031052864 66551184 18075296
02:15:08 4664 8192000000 9975856336 10324992000 980873536 10.693 100 87 174 1 1077585568 78853104 17858928
02:16:15 3855 8192000000 9980890816 10337710080 1005808720 10.935 100 81 164 2 1098161760 75702096 16650944
02:17:21 8096 8192000000 9993336256 10357534720 1026392704 11.134 100 89 174 2 1108054784 64195040 17467040
02:18:30 1442 8192000000 10046010000 10414460928 1055411472 11.413 100 94 189 0 1156389648 81590032 19388144
02:19:33 26095 8192000000 10008971600 10391461888 1066119568 11.515 100 61 122 2 1137610784 57868640 13622576
02:21:01 8621 8192000000 9985879488 10376957952 1070752544 11.559 100 34 67 1 1101508448 24264400 6491504
02:22:11 7118 8192000000 10004645328 10403631104 1074377728 11.594 100 29 58 0 1121588128 41587616 5622784
02:23:19 3799 8192000000 9991513760 10383196160 1078181152 11.630 100 44 87 0 1104574960 17664928 8728880
02:24:23 66909 8192000000 9992603616 10396831744 1073409664 11.585 99 32 62 1 1096393680 16865472 6118544
02:26:31 6715 8192000000 10012528768 10417692672 1082040352 11.667 99 34 69 0 1107237616 17544688 7652576
02:27:40 6281 8192000000 10011947344 10415796224 1077357872 11.622 99 48 93 2 1121190096 32771664 11060560
02:28:53 3190 8192000000 9970415808 10355097600 1057668528 11.434 99 29 59 3 1072713808 6919520 8125760
02:29:58 26130 8192000000 9984396800 10357686272 1032523648 11.193 96 64 130 0 1064581488 17284464 14773376
02:31:25 6012 8192000000 9951917072 10336468992 1022255936 11.094 97 43 82 2 1042378800 11271184 8851680
02:32:35 5458 8192000000 9927730336 10310049792 1005210976 10.929 98 52 104 2 1024897152 8098464 11587712
02:33:42 7873 8192000000 9949049104 10341031936 1032347056 11.191 99 54 108 1 1054546320 9027440 13171824
02:34:52 44410 8192000000 9966370272 10376237056 1049538464 11.356 100 41 82 1 1086121632 27921392 8661776
02:36:41 8074 8192000000 9991333520 10394890240 1068081248 11.534 100 47 93 1 1099654336 21279968 10293120
02:37:55 20802 8192000000 10033454144 10430898176 1094665264 11.787 100 40 81 1 1137991232 35313120 8012848
02:39:22 32844 8192000000 10079691280 10482614272 1115657104 11.986 100 54 108 1 1200134784 73561936 10915744
02:41:00 98688 8192000000 10102641280 10533216256 1129255424 12.114 100 31 63 0 1226000176 89335200 7409552
02:44:08 28815 8192000000 10149954032 10572349440 1149909104 12.309 100 32 64 1 1260360448 103332640 7118704
02:45:43 11020 8192000000 10162925792 10581610496 1156250416 12.368 100 49 92 1 1269576576 102822144 10504016
02:46:58 11181 8192000000 10204580160 10616332288 1167184240 12.471 100 60 120 0 1304382192 124005200 13192752
02:48:12 3260 8192000000 10245674960 10656940032 1172297968 12.518 99 71 139 1 1341591152 153648992 15644192
02:49:20 2468 8192000000 10288882656 10702876672 1185275296 12.639 100 67 134 0 1390387872 189807760 15304816
02:50:28 5615 8192000000 10331076736 10746920960 1177223456 12.564 99 75 151 0 1432077984 238210016 16644512
02:51:38 8566 8192000000 10357340720 10770288640 1186312768 12.649 100 70 139 0 1455141040 252923520 15904752
02:52:57 2312 8192000000 10362646496 10771529728 1181961248 12.608 99 82 164 0 1458883216 257952880 18969088
02:54:05 1452 8192000000 10384849296 10789777408 1162480080 12.426 99 93 187 3 1468790192 284230608 22079504
02:55:15 2462 8192000000 10346498064 10761527296 1150535216 12.315 99 73 145 1 1444625776 276947696 17142864
02:56:25 1944 8192000000 10416765312 10819989504 1164206720 12.443 100 85 169 0 1501969952 317935600 19827632
02:57:31 17268 8192000000 10321518816 10744766464 1141024336 12.225 98 40 80 0 1421529136 270348448 10156352
02:58:55 1515 8192000000 10305936288 10722910208 1130757296 12.129 99 77 155 2 1398187712 248852912 18577504
02:59:59 17372 8192000000 10272860032 10705788928 1157273424 12.378 100 37 74 0 1396831456 230104080 9453952
03:01:21 5750 8192000000 10235517872 10693083136 1140188416 12.217 100 65 127 0 1348422992 193520848 14713728
03:02:32 27569 8192000000 10189745616 10656792576 1141674624 12.231 100 18 36 0 1312311776 165908544 4728608
03:04:10 38042 8192000000 10072939648 10563686400 1103012560 11.866 97 10 20 1 1200311376 95188368 2110448
03:05:49 44393 8192000000 10051151792 10483687424 1113549840 11.966 98 47 94 1 1150022032 27105184 9367008
03:07:34 18777 8192000000 10094175152 10514644992 1114720784 11.977 98 53 103 0 1171617776 46207728 10689264
03:08:54 183524 8192000000 10071838368 10510000128 1050213344 11.363 93 16 32 0 1136562192 82768736 3580112
03:13:01 27922 8192000000 10068404416 10510315520 1112384560 11.955 98 15 31 1 1155623872 40157584 3081728
03:14:31 126748 8192000000 10058451568 10517131264 1107937088 11.913 97 20 40 1 1161924480 49792736 4194656
03:17:43 27632 8192000000 10052240288 10534436864 1122483456 12.050 99 8 16 0 1160926336 36623408 1819472
03:19:16 45772 8192000000 10075738688 10523115520 1117443280 12.003 99 16 32 0 1177870576 57288064 3139232
03:21:26 73282 8192000000 10084676656 10566135808 1134226416 12.161 100 15 30 0 1230029264 91864576 3938272
03:23:45 53582 8192000000 10089512976 10537529344 1136725856 12.185 100 26 48 2 1175085888 33425408 4934624
03:25:39 42530 8192000000 10106665200 10557165568 1141350928 12.228 100 28 56 0 1215695776 68447616 5897232
03:27:28 58661 8192000000 10076388768 10550407168 1124047104 12.065 98 30 60 0 1200928624 70363568 6517952
03:29:31 34395 8192000000 10122779792 10579697664 1136017952 12.178 99 40 80 0 1208411952 64267952 8126048
03:31:12 44203 8192000000 10120444576 10571980800 1141300064 12.228 99 22 44 1 1215431408 69770096 4361248
03:33:02 37987 8192000000 10101626256 10541264896 1156622096 12.372 100 38 76 0 1199960512 35819728 7518688
03:34:45 85224 8192000000 10094174896 10535014400 1126570688 12.089 98 50 100 0 1189991840 53192144 10229008
03:37:24 75031 8192000000 10093522544 10544807936 1119006336 12.018 97 11 22 0 1170063008 49032400 2024272
03:39:40 59731 8192000000 10074165568 10580086784 1112188240 11.953 97 10 20 0 1187693872 73465088 2040544
03:41:41 81536 8192000000 10099155168 10592464896 1113185968 11.963 97 12 24 0 1190184144 74696144 2302032
03:44:13 66961 8192000000 10071477696 10506006528 1081582976 11.663 95 32 64 1 1114301504 25882864 6835664
03:46:20 63964 8192000000 10095133264 10579771392 1112373808 11.955 98 24 48 0 1183348352 65774944 5199600
03:48:25 63670 8192000000 10118048624 10599096320 1101847552 11.855 97 25 50 0 1157134272 50220928 5065792
03:50:29 50647 8192000000 10059393232 10487513088 1102084720 11.857 98 26 52 1 1132451536 25075008 5291808
03:52:20 90057 8192000000 10088188848 10583863296 1095707328 11.797 97 17 34 0 1163606160 64097328 3801504
03:55:24 64290 8192000000 10071336544 10553380864 1100776992 11.845 98 17 32 2 1171881168 68086560 3017616
03:57:46 65818 8192000000 10074380576 10513154048 1102099968 11.858 98 10 20 0 1166158064 61888480 2169616
03:59:53 77685 8192000000 10106563760 10628546560 1131870384 12.139 100 12 24 0 1264102080 129195280 3036416
04:02:30 51051 8192000000 10086845536 10534629376 1120322288 12.030 99 17 34 0 1159453200 35637600 3493312
04:04:21 46047 8192000000 10017258464 10450485248 1053303440 11.392 93 17 35 0 1080663296 23943200 3416656
04:06:15 48639 8192000000 10102383232 10543087616 1092175616 11.763 98 39 78 1 1172453872 72405280 7872976
04:08:05 71093 8192000000 10115106352 10597580800 1095260288 11.793 97 20 38 2 1180847248 81637120 3949840
04:10:20 64680 8192000000 10049239808 10513068032 1094151328 11.782 97 11 22 1 1196790768 100179936 2459504
04:12:25 75920 8192000000 10085489760 10528579584 1061531984 11.471 93 12 24 0 1094436912 31102592 1802336
04:14:42 103176 8192000000 10139462960 10603606016 1122593248 12.051 99 36 72 0 1223696752 93499488 7604016
04:17:55 72650 8192000000 10091122752 10602577920 1124740784 12.072 99 7 14 0 1209351520 83094336 1516400
04:20:09 102845 8192000000 10089379776 10522677248 1116613104 11.995 98 25 50 1 1154415520 32018608 5783808
04:22:52 71695 8192000000 10102917408 10565169152 1141877872 12.233 100 22 43 1 1205278432 58572192 4828368
04:25:11 125504 8192000000 10111644528 10583355392 1125352576 12.078 98 19 38 0 1217276416 87628096 4295744
04:28:17 58401 8192000000 10107638240 10546622464 1133783008 12.157 99 31 62 0 1188053824 47648224 6622592
04:30:18 24898 8192000000 10093763760 10534465536 1134191984 12.161 99 39 77 0 1169984064 27762656 8029424
04:31:49 69015 8192000000 10077733584 10591334400 1132768368 12.147 99 13 26 1 1180926224 45702000 2455856
04:35:19 28615 8192000000 10084171888 10541494272 1110030656 11.933 97 31 62 2 1166817552 50038576 6748320
04:36:48 29283 8192000000 9443242512 9825660928 557755840 6.374 50 7 14 0 569182208 10171312 1255056

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-09-2021 04:39:25 to 22-09-2021 04:39:39.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-09-2021 04:39:40 to 22-09-2021 04:39:42.

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          227245
MON$OLDEST_ACTIVE               227803
MON$OLDEST_SNAPSHOT             227803
MON$NEXT_TRANSACTION            227803
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                       2026208
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2613 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2613 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2613 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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'

22.09.21 04:39:43. 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-09-22 04:39:46 No severe PSQL-related problems occured
22.09.21 04:39:47. End of report.