Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.02.2021 04: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 16668852 kB
MemAvailable 31126436 kB
Buffers 20 kB
Cached 14931056 kB
SwapCached 4772 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-11 04:26:56
DTS_END                         2021-02-11 04:26:56.9500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54778
TRN_ID                          227653
ATT_ID                          591
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: 8621.52 20727 1034582
customer order: creation 169.83 458 20380
customer order: refuse 14.16 379 1699
order to supplier: creation 45.11 1424 5413
order to supplier: removal 3.24 142011 389
invoice (draft): creation 45.02 3814 5402
invoice (draft): removal 47.35 1223 5682
invoice accept: apply 39.64 19017 4757
invoice accept: cancel 43.13 1118 5176
customer reserve: creation 3640.37 183 436844
customer reserve: removal 4279.04 83 513485
realization accept: apply 82.21 224 9865
realization accept: cancel 86.94 142 10433
payment from customer: creation 59.03 151 7084
payment from customer: removal 5.18 122 621
payment to supplier: creation 47.28 156 5673
payment to supplier: removal 3.49 138 419
service: total inventory turnovers 3.59 3946 431
service: total monetary turnovers 6.21 620 745
service: aggregate perf. data 0.67 24595 80
service: refresh index statistics 0.03 214738 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 11-02-2021 04:27:37 to 11-02-2021 04:27:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2744
TEST_TIME 2 3747
TEST_TIME 3 3877
TEST_TIME 4 3805
TEST_TIME 5 3705
TEST_TIME 6 4113
TEST_TIME 7 4024
TEST_TIME 8 4208
TEST_TIME 9 4538
TEST_TIME 10 4408
TEST_TIME 11 4680
TEST_TIME 12 4822
TEST_TIME 13 5048
TEST_TIME 14 5408
TEST_TIME 15 5804
TEST_TIME 16 5946
TEST_TIME 17 5994
TEST_TIME 18 6121
TEST_TIME 19 6173
TEST_TIME 20 6303
TEST_TIME 21 6445
TEST_TIME 22 6959
TEST_TIME 23 7270
TEST_TIME 24 7438
TEST_TIME 25 7738
TEST_TIME 26 7923
TEST_TIME 27 8282
TEST_TIME 28 8379
TEST_TIME 29 8723
TEST_TIME 30 9696
TEST_TIME 31 10812
TEST_TIME 32 10830
TEST_TIME 33 10596
TEST_TIME 34 10344
TEST_TIME 35 10352
TEST_TIME 36 10292
TEST_TIME 37 10144
TEST_TIME 38 9950
TEST_TIME 39 9922
TEST_TIME 40 9808
TEST_TIME 41 9706
TEST_TIME 42 9610
TEST_TIME 43 9631
TEST_TIME 44 9491
TEST_TIME 45 9387
TEST_TIME 46 9268
TEST_TIME 47 9159
TEST_TIME 48 9048
TEST_TIME 49 8991
TEST_TIME 50 8976
TEST_TIME 51 8908
TEST_TIME 52 8872
TEST_TIME 53 8934
TEST_TIME 54 8992
TEST_TIME 55 8923
TEST_TIME 56 8913
TEST_TIME 57 8984
TEST_TIME 58 9047
TEST_TIME 59 9054
TEST_TIME 60 9136
TEST_TIME 61 9128
TEST_TIME 62 9055
TEST_TIME 63 9076
TEST_TIME 64 9050
TEST_TIME 65 9024
TEST_TIME 66 9074
TEST_TIME 67 9105
TEST_TIME 68 9074
TEST_TIME 69 8997
TEST_TIME 70 8905
TEST_TIME 71 8904
TEST_TIME 72 8848
TEST_TIME 73 8818
TEST_TIME 74 8811
TEST_TIME 75 8770
TEST_TIME 76 8747
TEST_TIME 77 8771
TEST_TIME 78 8811
TEST_TIME 79 8805
TEST_TIME 80 8791
TEST_TIME 81 8767
TEST_TIME 82 8819
TEST_TIME 83 8802
TEST_TIME 84 8773
TEST_TIME 85 8850
TEST_TIME 86 8883
TEST_TIME 87 8870
TEST_TIME 88 8777
TEST_TIME 89 8728
TEST_TIME 90 8670
TEST_TIME 91 8641
TEST_TIME 92 8637
TEST_TIME 93 8617
TEST_TIME 94 8613
TEST_TIME 95 8702
TEST_TIME 96 8700
TEST_TIME 97 8689
TEST_TIME 98 8699
TEST_TIME 99 8687
TEST_TIME 100 8686
TEST_TIME 101 8692
TEST_TIME 102 8732
TEST_TIME 103 8754
TEST_TIME 104 8751
TEST_TIME 105 8816
TEST_TIME 106 8820
TEST_TIME 107 8813
TEST_TIME 108 8736
TEST_TIME 109 8683
TEST_TIME 110 8662
TEST_TIME 111 8627
TEST_TIME 112 8602
TEST_TIME 113 8573
TEST_TIME 114 8566
TEST_TIME 115 8589
TEST_TIME 116 8574
TEST_TIME 117 8618
TEST_TIME 118 8658
TEST_TIME 119 8624
TEST_TIME 120 8623

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-02-2021 04:27:41 to 11-02-2021 04:27:45.

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 1439552 1439552 0 0.00 0 32275 8 0 0
doc_list_biud 1439552 1439552 0 0.00 0 121851 38 0 0
sp_add_invoice_to_stock 4919 4757 162 3.29 68 161965 19018 0 162
sp_cancel_adding_invoice 5176 5176 0 0.00 6 80871 1119 0 0
sp_cancel_client_order 1699 1699 0 0.00 4 7760 380 0 0
sp_cancel_customer_reserve 513485 513485 0 0.00 1 19510 84 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 2 8499 122 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 7687 139 0 0
sp_cancel_supplier_invoice 5695 5682 13 0.22 2 121860 1224 0 13
sp_cancel_supplier_order 389 389 0 0.00 8 1450526 142011 0 0
sp_cancel_write_off 10433 10433 0 0.00 1 13070 143 0 0
sp_client_order 20380 20380 0 0.00 4 23461 458 0 0
sp_customer_reserve 436844 436844 0 0.00 0 42835 184 0 0
sp_fill_shopping_cart 31178 31178 0 0.00 1 12354 48 0 0
sp_fill_shopping_cart_clo_res 436837 436837 0 0.00 0 24724 18 0 0
sp_get_clo_for_invoice 4750 4750 0 0.00 0 43785 436 0 0
sp_kill_cost_storno 16649 16649 0 0.00 0 13038 80 0 0
sp_kill_qstorno_ret_qs2qd 1043474 1043474 0 0.00 0 121849 45 0 0
sp_kill_qty_storno 551479 551479 0 0.00 0 121851 97 0 0
sp_lock_selected_doc 20230 20230 0 0.00 0 11623 7 0 0
sp_make_cost_storno 27372 27372 0 0.00 0 21472 74 0 0
sp_multiply_rows_for_pdistr 27372 27372 0 0.00 0 16736 43 0 0
sp_multiply_rows_for_qdistr 20380 20380 0 0.00 0 22594 262 0 0
sp_pay_from_customer 7084 7084 0 0.00 4 19126 152 0 0
sp_pay_to_supplier 5673 5673 0 0.00 5 19244 157 0 0
sp_payment_common 12757 12757 0 0.00 1 13998 25 0 0
sp_qd_handle_on_cancel_clo 1699 1699 0 0.00 0 3337 332 0 0
sp_qd_handle_on_invoice_upd_sts 9926 9926 0 0.00 0 13627 475 0 0
sp_qd_handle_on_reserve_upd_sts 9865 9865 0 0.00 0 10888 60 0 0
sp_reserve_write_off 9865 9865 0 0.00 7 19514 224 0 0
sp_supplier_invoice 5402 5402 0 0.00 6 112806 3815 0 0
sp_supplier_order 5413 5413 0 0.00 5 42511 1425 0 0
srv_aggregate_perf_data 81 80 1 1.23 3025 324014 24596 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1254 133322 44151 0 0
srv_find_qd_qs_mism 988629 988629 0 0.00 0 11527 6 0 0
srv_make_invnt_saldo 431 431 0 0.00 53 135315 3946 0 0
srv_make_money_saldo 745 745 0 0.00 1 21957 620 0 0
srv_recalc_idx_stat 4 4 0 0.00 131019 323885 214738 0 0
t$perf-abend:sp_add_invoice_to_stock 162 162 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4757 4757 0 0.00 0 42873 206 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 2582 98 0 0
t$perf-norm:sp_cancel_client_order 1699 1699 0 0.00 0 27188 30 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 882 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 1290 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 341 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 707 11 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 21124 1611 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 4632 15 0 0
t$perf-norm:sp_client_order 20380 20380 0 0.00 0 15201 25 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 5814 16 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 16230 15 0 0
t$perf-norm:sp_pay_to_supplier 5673 5673 0 0.00 0 8017 10 0 0
t$perf-norm:sp_reserve_write_off 9865 9865 0 0.00 0 5780 11 0 0
t$perf-norm:sp_supplier_invoice 5402 5402 0 0.00 0 11536 28 0 0
t$perf-norm:sp_supplier_order 5413 5413 0 0.00 0 17322 24 0 0
t$perf-norm:srv_aggregate_perf_data 80 80 0 0.00 0 228 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 109 4 0 0
t$perf-norm:srv_make_invnt_saldo 431 431 0 0.00 0 1074 13 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 399 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 7 4 0 0
v_add_invoice_to_stock 9822 9498 324 3.29 0 8033 23 0 324
v_all_customers 37646 37646 0 0.00 0 247 1 0 0
v_all_suppliers 29834 29834 0 0.00 0 111 1 0 0
v_all_wares 40760 40760 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 5282 29 0 0
v_cancel_client_order 3398 3398 0 0.00 0 6574 10 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 4159 13 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 0 5869 16 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 2260 16 0 0
v_cancel_supplier_invoice 768 742 26 3.38 0 2157 16 0 26
v_cancel_supplier_order 778 778 0 0.00 0 780 5 0 0
v_cancel_write_off 2052 2052 0 0.00 0 4383 20 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 590 2 0 0
v_max_id_clo_res 985 985 0 0.00 0 3548 22 0 0
v_max_id_ord_sup 5386 5386 0 0.00 0 5742 7 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 1712 3 0 0
v_max_non_paid_realizn 3525 3525 0 0.00 0 4527 4 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 10491 9 0 0
v_min_id_clo_res 985 985 0 0.00 0 10473 22 0 0
v_min_id_ord_sup 5386 5386 0 0.00 0 9164 13 0 0
v_min_non_paid_invoice 5673 5673 0 0.00 0 6585 16 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 13993 17 0 0
v_our_firm 10232 10232 0 0.00 0 19 1 0 0
v_reserve_write_off 19726 19726 0 0.00 0 10791 10 0 0
x_lock_depdocs_on_canc_invoice 10487 10487 0 0.00 0 19532 137 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 4118 360 0 0
x_make_qstorno_1000_1200 5413 5413 0 0.00 1 42241 1329 0 0
x_make_qstorno_1200_2000 5402 5402 0 0.00 3 112787 3629 0 0
x_make_qstorno_2100_3300 436844 436844 0 0.00 0 42832 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-02-2021 04:27:46 to 11-02-2021 04:27:50.

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
01:57:00 6 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 26632 8192000000 9727853072 10028244992 805615664 8.953 100 88 176 0 834544304 7494928 21433712
01:59:43 19454 8192000000 9785955856 10095788032 854000960 9.440 100 90 180 1 887716768 12428688 21287120
02:01:15 23656 8192000000 9832752144 10156253184 891670704 9.816 100 82 164 2 934871760 24149424 19051632
02:02:43 21071 8192000000 9881870624 10217938944 929034624 10.185 100 79 158 1 995674512 49379600 17260288
02:04:14 7554 8192000000 9941499920 10288910336 969847600 10.585 100 79 158 0 1058900256 72648752 16403904
02:05:26 4923 8192000000 9958851584 10318319616 1005314448 10.930 100 81 158 3 1070035760 48354640 16366672
02:06:34 9523 8192000000 9990563904 10355089408 1028283984 11.152 100 78 155 1 1107047312 63837504 14925824
02:07:46 2735 8192000000 10015596736 10381410304 1049941168 11.360 100 92 182 1 1128199120 59500352 18757600
02:08:50 1960 8192000000 10036120272 10409009152 1062340896 11.479 100 78 157 1 1148313488 70448720 15523872
02:09:55 2054 8192000000 10031069968 10405117952 1071953808 11.571 100 90 181 0 1151309760 60486400 18869552
02:11:00 4641 8192000000 10009130080 10397454336 1075341392 11.603 100 47 94 0 1115053968 30026416 9686160
02:12:07 4942 8192000000 10011040528 10409897984 1080614208 11.653 100 34 69 1 1137866320 50479552 6772560
02:13:13 4757 8192000000 9996133696 10399805440 1085256784 11.698 100 33 66 0 1119155456 27345840 6552832
02:14:18 40672 8192000000 10031479184 10416840704 1094176464 11.782 100 83 167 0 1127563312 14837808 18549040
02:16:00 9836 8192000000 10011562512 10417991680 1098950000 11.828 100 35 69 2 1145498144 38752912 7795232
02:17:14 3333 8192000000 10003140864 10405281792 1098840512 11.827 100 33 65 3 1122123008 15119440 8163056
02:18:17 6144 8192000000 9988253936 10389254144 1065010192 11.504 97 27 56 2 1091491696 18649120 7832384
02:19:27 54674 8192000000 9971088464 10370555904 1022722640 11.098 95 27 54 3 1052220144 23456080 6041424
02:21:26 5910 8192000000 9930860864 10319347712 980003856 10.684 94 49 97 2 1007853232 16742848 11106528
02:22:33 11282 8192000000 9951775696 10341535744 1011499136 10.990 98 25 50 2 1036713616 19536384 5678096
02:23:46 3268 8192000000 9952993968 10336657408 1045457328 11.317 100 47 93 1 1073198352 16647136 11093888
02:24:54 13144 8192000000 9952143968 10338410496 1033509808 11.202 99 23 45 0 1052147120 13859744 4777568
02:26:09 10227 8192000000 9971553040 10367401984 1061093056 11.467 100 45 90 1 1087925296 16757120 10075120
02:27:31 23015 8192000000 10014155328 10405801984 1086441712 11.709 100 54 105 3 1121827648 25062480 10323456
02:29:03 56016 8192000000 10065455024 10471915520 1109839024 11.931 100 38 75 1 1173958720 56689056 7430640
02:31:18 13883 8192000000 10111001488 10517184512 1130148176 12.123 100 50 98 6 1216158960 75506432 10504352
02:32:35 19640 8192000000 10123634384 10539880448 1142039968 12.235 100 35 69 0 1240276592 90474864 7761760
02:33:57 6978 8192000000 10201813568 10606075904 1154486128 12.352 100 59 118 2 1309069184 141933856 12649200
02:35:10 9746 8192000000 10254074352 10664230912 1163112000 12.432 100 56 112 2 1362162640 186019232 13031408
02:36:25 22034 8192000000 10287961520 10699161600 1175808320 12.551 100 60 118 1 1383671840 195067088 12796432
02:37:54 11751 8192000000 10316168096 10734522368 1186550704 12.651 100 52 104 0 1422402496 223900400 11951392
02:39:11 3187 8192000000 10351037232 10765889536 1192869200 12.710 100 68 134 3 1460303744 252375232 15059312
02:40:18 1723 8192000000 10388441536 10795708416 1197800944 12.756 100 84 166 2 1486523408 269955408 18767056
02:41:24 7842 8192000000 10433036336 10859823104 1198425840 12.762 100 79 157 1 1533868544 317666112 17776592
02:42:36 5820 8192000000 10447695728 10861711360 1190538048 12.688 100 73 146 1 1546879984 339337376 17004560
02:43:50 2540 8192000000 10468990272 10881359872 1197227280 12.751 100 83 163 0 1568723024 352869872 18625872
02:44:59 2681 8192000000 10499827584 10903814144 1185227264 12.639 100 93 186 1 1593269120 387585808 20456048
02:46:07 1469 8192000000 10546542400 10958401536 1176045344 12.553 100 92 183 3 1636419136 439533136 20840656
02:47:15 1253 8192000000 10558344960 10958987264 1165784288 12.457 100 95 188 1 1652868608 464942032 22142288
02:48:20 1453 8192000000 10560792368 11006763008 1175586368 12.549 100 93 186 0 1653936992 456029168 22321456
02:49:27 4278 8192000000 10512239600 10941874176 1151405744 12.323 99 82 164 0 1605603424 433434912 20762768
02:50:37 1498 8192000000 10581777024 11003707392 1180871056 12.598 100 95 189 1 1676134368 472638208 22625104
02:51:45 12467 8192000000 10563388880 10989285376 1182616720 12.615 100 44 88 0 1659265744 465319824 11329200
02:53:01 8538 8192000000 10565524720 11022606336 1164610896 12.446 100 45 89 0 1667157328 490783280 11763152
02:54:26 19419 8192000000 10505520176 10970517504 1125743920 12.081 99 34 68 0 1614926496 479228128 9954448
02:55:50 14759 8192000000 10462051952 10932232192 1124957856 12.074 100 30 60 0 1579399024 445801344 8639824
02:57:49 78551 8192000000 10108134944 10577448960 1151226080 12.321 100 18 36 2 1220345680 64734048 4385552
03:00:10 70568 8192000000 10110276544 10612326400 1161779232 12.420 100 29 58 0 1249254864 81411376 6064256
03:02:29 48062 8192000000 10083757200 10577453056 1142126192 12.236 99 14 28 0 1194940320 49758704 3055424
03:04:24 59173 8192000000 10081328544 10570944512 1157430208 12.379 100 14 29 1 1199173584 38953280 2790096
03:06:31 44812 8192000000 10102520496 10549960704 1146257264 12.274 100 25 51 2 1185212944 33054384 5901296
03:08:29 22934 8192000000 10081976768 10519838720 1134798944 12.167 99 41 76 4 1187334000 43200288 9334768
03:09:53 66284 8192000000 10073874736 10526957568 1127974048 12.102 99 13 26 2 1182911440 52399936 2537456
03:11:59 75607 8192000000 10058714336 10519969792 1133789536 12.157 99 17 34 0 1157979632 20943024 3247072
03:14:18 17090 8192000000 10079664192 10526666752 1150271120 12.312 100 25 49 1 1183051584 27843424 4937040
03:15:38 16626 8192000000 10143810304 10575388672 1136053328 12.178 99 63 124 1 1234313856 84658480 13602048
03:17:03 67526 8192000000 10139292848 10616573952 1138656208 12.203 100 16 32 0 1256138944 113878080 3604656
03:19:12 69074 8192000000 10119730144 10638045184 1125913744 12.083 99 17 34 1 1258342864 128440240 3988880
03:21:22 117122 8192000000 10130373216 10585071616 1116269856 11.992 98 16 32 1 1166747696 47253056 3224784
03:24:19 43686 8192000000 10098362672 10531459072 1126269312 12.086 98 30 58 0 1173598624 40429408 6899904
03:26:06 53809 8192000000 10065473216 10561032192 1131078592 12.132 99 18 37 1 1195758848 61014096 3666160
03:28:12 64776 8192000000 10081955072 10536550400 1118913152 12.017 98 36 71 2 1164407888 38259856 7234880
03:30:20 78570 8192000000 10103837696 10577473536 1133495872 12.154 99 14 27 3 1172394512 36258256 2640384
03:32:40 58075 8192000000 10095564544 10527121408 1081181728 11.659 95 19 36 1 1143907376 58630880 4094768
03:34:39 133322 8192000000 10096080368 10530451456 1126395072 12.087 99 20 40 1 1191826992 61061792 4370128
03:37:55 60635 8192000000 10079048384 10540089344 1125137232 12.075 99 15 30 1 1177457232 49047184 3272816
03:40:55 28939 8192000000 10083095120 10551382016 1119052304 12.018 99 18 36 0 1180727456 57652864 4022288
03:42:25 79601 8192000000 10075268912 10549166080 1079639392 11.644 95 12 24 1 1116309152 34300704 2369056
03:46:07 72049 8192000000 10051492832 10506391552 1075270576 11.602 96 21 42 0 1114068928 34357104 4441248
03:48:20 49531 8192000000 10075946512 10510905344 1094409584 11.785 98 23 46 1 1150795376 52116544 4269248
03:50:12 70120 8192000000 10114680240 10583678976 1120064112 12.028 100 40 80 0 1213223904 84318688 8841104
03:53:06 101949 8192000000 10030918368 10469851136 1103499008 11.871 99 25 50 3 1134248336 26394800 4354528
03:56:25 87379 8192000000 10083453216 10553561088 1122934960 12.055 100 10 20 0 1199171072 74248464 1987648
03:59:07 70884 8192000000 10102994384 10580451328 1115194256 11.982 98 14 28 0 1200169696 82408528 2566912
04:01:27 108663 8192000000 10073407136 10516234240 1120275872 12.030 98 19 38 1 1164797440 40383616 4137952
04:04:27 49501 8192000000 10103046784 10560655360 1129455168 12.116 99 38 76 2 1175611184 37997152 8158864
04:06:24 22478 8192000000 10147535840 10599096320 1147702560 12.288 100 40 80 2 1214225808 58463568 8059680
04:07:47 66405 8192000000 10082550928 10550677504 1089918896 11.742 95 19 36 1 1148570320 54581552 4069872
04:10:09 59603 8192000000 10097323968 10569097216 1104096192 11.876 98 9 18 0 1178118064 72180240 1841632
04:12:09 22561 8192000000 10070668624 10509512704 1101326896 11.850 98 29 58 3 1138118336 30813344 5978096
04:13:44 120928 8192000000 10062178336 10531299328 1125756608 12.081 100 19 38 1 1182375360 52731472 3887280
04:16:45 29168 8192000000 10058932672 10518089728 1112385680 11.955 99 2 4 0 1127129344 14515888 227776
04:18:16 64797 8192000000 10085148240 10547929088 1123841920 12.063 99 19 36 2 1157668368 29762672 4063776
04:20:21 66113 8192000000 10134153792 10591817728 1096033392 11.800 97 23 46 0 1183106400 81567120 5505888
04:22:27 50623 8192000000 10118571264 10587361280 1136066816 12.179 100 23 46 1 1226560080 85743504 4749760
04:24:19 67990 8192000000 10077101664 10496983040 1076606320 11.615 95 33 66 0 1113511136 29686592 7218224
04:26:29 38026 8192000000 9205437424 9493053440 335523248 3.934 30 13 24 1 366126592 28000112 2603232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-02-2021 04:27:51 to 11-02-2021 04:28:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 162 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 11-02-2021 04:28:07 to 11-02-2021 04:28:09.

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          227652
MON$OLDEST_ACTIVE               227921
MON$OLDEST_SNAPSHOT             227921
MON$NEXT_TRANSACTION            227921
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                       2072992
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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'

11.02.21 04:28:10. 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-11 04:28:13 No severe PSQL-related problems occured
11.02.21 04:28:14. End of report.