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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16816060 kB
MemAvailable 31235848 kB
Buffers 0 kB
Cached 14899268 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-08 13:26:56
DTS_END                         2021-02-08 13:26:56.7020
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42128
TRN_ID                          226228
ATT_ID                          615
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: 8794.48 20197 1055337
customer order: creation 163.81 411 19657
customer order: refuse 13.27 348 1592
order to supplier: creation 42.62 1470 5114
order to supplier: removal 3.23 120490 388
invoice (draft): creation 43.74 3797 5249
invoice (draft): removal 48.08 954 5769
invoice accept: apply 40.93 19135 4912
invoice accept: cancel 43.35 922 5202
customer reserve: creation 3831.45 185 459774
customer reserve: removal 4281.85 71 513822
realization accept: apply 81.01 205 9721
realization accept: cancel 77.49 139 9299
payment from customer: creation 56.84 146 6821
payment from customer: removal 5.09 98 611
payment to supplier: creation 47.59 152 5711
payment to supplier: removal 3.51 114 421
service: total inventory turnovers 3.58 3610 429
service: total monetary turnovers 6.10 522 732
service: aggregate perf. data 0.91 14060 109
service: refresh index statistics 0.03 237119 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-02-2021 13:28:36 to 08-02-2021 13:28:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1825
TEST_TIME 2 2427
TEST_TIME 3 2986
TEST_TIME 4 2796
TEST_TIME 5 3686
TEST_TIME 6 4038
TEST_TIME 7 4368
TEST_TIME 8 4784
TEST_TIME 9 5144
TEST_TIME 10 5054
TEST_TIME 11 5157
TEST_TIME 12 5208
TEST_TIME 13 5248
TEST_TIME 14 5510
TEST_TIME 15 5685
TEST_TIME 16 5789
TEST_TIME 17 6003
TEST_TIME 18 6386
TEST_TIME 19 6701
TEST_TIME 20 7256
TEST_TIME 21 7228
TEST_TIME 22 7602
TEST_TIME 23 8033
TEST_TIME 24 7970
TEST_TIME 25 8547
TEST_TIME 26 8424
TEST_TIME 27 9589
TEST_TIME 28 10514
TEST_TIME 29 10506
TEST_TIME 30 10620
TEST_TIME 31 10425
TEST_TIME 32 10205
TEST_TIME 33 10105
TEST_TIME 34 9955
TEST_TIME 35 9769
TEST_TIME 36 9724
TEST_TIME 37 9820
TEST_TIME 38 9763
TEST_TIME 39 9723
TEST_TIME 40 9765
TEST_TIME 41 9714
TEST_TIME 42 9639
TEST_TIME 43 9550
TEST_TIME 44 9465
TEST_TIME 45 9347
TEST_TIME 46 9380
TEST_TIME 47 9467
TEST_TIME 48 9403
TEST_TIME 49 9493
TEST_TIME 50 9423
TEST_TIME 51 9471
TEST_TIME 52 9528
TEST_TIME 53 9511
TEST_TIME 54 9449
TEST_TIME 55 9514
TEST_TIME 56 9547
TEST_TIME 57 9493
TEST_TIME 58 9404
TEST_TIME 59 9279
TEST_TIME 60 9291
TEST_TIME 61 9217
TEST_TIME 62 9185
TEST_TIME 63 9218
TEST_TIME 64 9184
TEST_TIME 65 9134
TEST_TIME 66 9158
TEST_TIME 67 9178
TEST_TIME 68 9230
TEST_TIME 69 9212
TEST_TIME 70 9224
TEST_TIME 71 9281
TEST_TIME 72 9270
TEST_TIME 73 9297
TEST_TIME 74 9287
TEST_TIME 75 9294
TEST_TIME 76 9383
TEST_TIME 77 9329
TEST_TIME 78 9225
TEST_TIME 79 9147
TEST_TIME 80 9139
TEST_TIME 81 9080
TEST_TIME 82 9080
TEST_TIME 83 9028
TEST_TIME 84 9038
TEST_TIME 85 9059
TEST_TIME 86 9025
TEST_TIME 87 9024
TEST_TIME 88 9048
TEST_TIME 89 9060
TEST_TIME 90 9061
TEST_TIME 91 9044
TEST_TIME 92 9022
TEST_TIME 93 9129
TEST_TIME 94 9140
TEST_TIME 95 9178
TEST_TIME 96 9099
TEST_TIME 97 9038
TEST_TIME 98 8991
TEST_TIME 99 8940
TEST_TIME 100 9004
TEST_TIME 101 8986
TEST_TIME 102 8952
TEST_TIME 103 8946
TEST_TIME 104 9009
TEST_TIME 105 9042
TEST_TIME 106 9044
TEST_TIME 107 9010
TEST_TIME 108 9012
TEST_TIME 109 9026
TEST_TIME 110 9052
TEST_TIME 111 9137
TEST_TIME 112 9074
TEST_TIME 113 9001
TEST_TIME 114 8981
TEST_TIME 115 8936
TEST_TIME 116 8932
TEST_TIME 117 8896
TEST_TIME 118 8869
TEST_TIME 119 8846
TEST_TIME 120 8798

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2021 13:28:40 to 08-02-2021 13:28:44.

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 1476871 1476871 0 0.00 0 34563 7 0 0
doc_list_biud 1476871 1476871 0 0.00 0 36426 31 0 0
sp_add_invoice_to_stock 5069 4912 157 3.09 35 186409 19136 0 157
sp_cancel_adding_invoice 5202 5202 0 0.00 5 91067 923 0 0
sp_cancel_client_order 1592 1592 0 0.00 4 14030 348 0 0
sp_cancel_customer_reserve 513822 513822 0 0.00 1 23213 71 0 0
sp_cancel_pay_from_customer 611 611 0 0.00 2 13399 98 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 6833 115 0 0
sp_cancel_supplier_invoice 5784 5769 15 0.25 2 26364 955 0 15
sp_cancel_supplier_order 388 388 0 0.00 10 1177854 120490 0 0
sp_cancel_write_off 9299 9299 0 0.00 1 11376 139 0 0
sp_client_order 19657 19657 0 0.00 4 46270 412 0 0
sp_customer_reserve 459774 459774 0 0.00 0 66689 185 0 0
sp_fill_shopping_cart 30009 30009 0 0.00 1 20607 45 0 0
sp_fill_shopping_cart_clo_res 459768 459768 0 0.00 0 21148 17 0 0
sp_get_clo_for_invoice 4906 4906 0 0.00 0 42532 412 0 0
sp_kill_cost_storno 15533 15533 0 0.00 0 11371 71 0 0
sp_kill_qstorno_ret_qs2qd 1043100 1043100 0 0.00 0 26358 38 0 0
sp_kill_qty_storno 550698 550698 0 0.00 0 36426 82 0 0
sp_lock_selected_doc 20097 20097 0 0.00 0 8137 7 0 0
sp_make_cost_storno 27158 27158 0 0.00 0 18983 67 0 0
sp_multiply_rows_for_pdistr 27158 27158 0 0.00 0 20040 38 0 0
sp_multiply_rows_for_qdistr 19657 19657 0 0.00 0 27366 223 0 0
sp_pay_from_customer 6821 6821 0 0.00 4 34359 147 0 0
sp_pay_to_supplier 5711 5711 0 0.00 5 28519 153 0 0
sp_payment_common 12532 12532 0 0.00 1 21173 35 0 0
sp_qd_handle_on_cancel_clo 1592 1592 0 0.00 0 14023 324 0 0
sp_qd_handle_on_invoice_upd_sts 10107 10107 0 0.00 0 36426 383 0 0
sp_qd_handle_on_reserve_upd_sts 9721 9721 0 0.00 0 7489 49 0 0
sp_reserve_write_off 9721 9721 0 0.00 6 34657 206 0 0
sp_supplier_invoice 5249 5249 0 0.00 7 134451 3798 0 0
sp_supplier_order 5114 5114 0 0.00 6 109731 1470 0 0
srv_aggregate_perf_data 110 109 1 0.90 2834 128599 14060 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1394 198114 44713 0 0
srv_find_qd_qs_mism 1007008 1007008 0 0.00 0 11152 6 0 0
srv_make_invnt_saldo 429 429 0 0.00 44 97125 3610 0 0
srv_make_money_saldo 732 732 0 0.00 1 11955 523 0 0
srv_recalc_idx_stat 4 4 0 0.00 156802 323573 237120 0 0
t$perf-abend:sp_add_invoice_to_stock 157 157 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 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 4912 4912 0 0.00 0 13508 168 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 0 3119 86 0 0
t$perf-norm:sp_cancel_client_order 1592 1592 0 0.00 0 7407 14 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 1855 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 611 611 0 0.00 0 5514 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 285 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1014 18 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 24134 1587 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 1104 9 0 0
t$perf-norm:sp_client_order 19657 19657 0 0.00 0 7939 18 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 5760 9 0 0
t$perf-norm:sp_pay_from_customer 6821 6821 0 0.00 0 6158 12 0 0
t$perf-norm:sp_pay_to_supplier 5711 5711 0 0.00 0 5312 10 0 0
t$perf-norm:sp_reserve_write_off 9721 9721 0 0.00 0 8991 10 0 0
t$perf-norm:sp_supplier_invoice 5249 5249 0 0.00 0 5161 16 0 0
t$perf-norm:sp_supplier_order 5114 5114 0 0.00 0 5806 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 594 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 608 5 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 290 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10120 9806 314 3.10 0 5661 18 0 314
v_all_customers 36052 36052 0 0.00 0 50 1 0 0
v_all_suppliers 29240 29240 0 0.00 0 17 1 0 0
v_all_wares 39312 39312 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 1990 12 0 0
v_cancel_client_order 3184 3184 0 0.00 0 3967 6 0 0
v_cancel_customer_prepayment 1222 1222 0 0.00 0 1927 7 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 3442 8 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 2605 12 0 0
v_cancel_supplier_invoice 844 814 30 3.55 0 3559 18 0 30
v_cancel_supplier_order 776 776 0 0.00 0 700 6 0 0
v_cancel_write_off 1990 1990 0 0.00 0 2184 16 0 0
v_max_id_clo_ord 5114 5114 0 0.00 0 4159 3 0 0
v_max_id_clo_res 933 933 0 0.00 0 3781 19 0 0
v_max_id_ord_sup 5239 5239 0 0.00 0 3948 4 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 123 2 0 0
v_max_non_paid_realizn 3505 3505 0 0.00 0 3975 4 0 0
v_min_id_clo_ord 5114 5114 0 0.00 0 19214 17 0 0
v_min_id_clo_res 933 933 0 0.00 0 9416 26 0 0
v_min_id_ord_sup 5239 5239 0 0.00 0 6631 8 0 0
v_min_non_paid_invoice 5711 5711 0 0.00 0 16708 21 0 0
v_min_non_paid_realizn 6821 6821 0 0.00 0 21168 28 0 0
v_our_firm 9892 9892 0 0.00 0 43 1 0 0
v_reserve_write_off 19442 19442 0 0.00 0 3967 6 0 0
x_lock_depdocs_on_canc_invoice 10564 10564 0 0.00 0 23279 130 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 17218 404 0 0
x_make_qstorno_1000_1200 5114 5114 0 0.00 2 109708 1384 0 0
x_make_qstorno_1200_2000 5249 5249 0 0.00 3 134370 3628 0 0
x_make_qstorno_2100_3300 459774 459774 0 0.00 0 66688 166 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-02-2021 13:28:44 to 08-02-2021 13:28:49.

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
10:57:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
10:58:01 30545 8192000000 9729585584 10026164224 802848832 8.925 100 88 176 0 832617936 7752992 22016112
10:59:37 24761 8192000000 9769535024 10074775552 837841328 9.278 100 85 170 0 869974256 11814416 20318512
11:01:07 16525 8192000000 9794711056 10109956096 862654896 9.527 100 87 174 0 903861072 21229024 19977152
11:02:39 17451 8192000000 9858292896 10188763136 909589456 9.993 100 76 152 0 970154416 43982016 16582944
11:04:00 6990 8192000000 9918136288 10263543808 962134768 10.510 100 67 134 0 1032231056 56106288 13990000
11:05:11 5880 8192000000 9961795696 10316066816 1001011856 10.888 100 83 165 0 1071671184 54305600 16353728
11:06:20 5627 8192000000 9974697952 10337845248 1027010288 11.140 100 82 164 0 1098029344 54707776 16311280
11:07:28 3138 8192000000 10035120160 10401120256 1046293664 11.325 100 88 174 0 1149976080 86074992 17607424
11:08:33 1691 8192000000 10050799440 10424700928 1064803856 11.502 100 94 188 0 1169062592 85131280 19127456
11:09:37 2010 8192000000 10045741808 10423009280 1074510048 11.595 100 85 170 1 1154084672 62134992 17439632
11:10:42 15077 8192000000 9988251376 10396958720 1077411488 11.623 100 23 47 0 1126635744 44611744 4612512
11:12:01 5116 8192000000 9995865200 10388176896 1084355216 11.689 100 37 74 0 1113489408 21294240 7839952
11:13:08 6119 8192000000 10007324608 10411364352 1096284240 11.802 100 49 99 2 1132604256 26339280 9980736
11:14:15 63316 8192000000 10022135344 10418421760 1098064208 11.819 100 49 98 2 1132216176 23729856 10422112
11:16:19 6290 8192000000 10020646160 10428207104 1105667456 11.891 100 50 102 2 1145164192 27895072 11601664
11:17:29 4400 8192000000 10010470432 10407194624 1075428656 11.604 98 48 95 1 1109791488 23078320 11284512
11:18:34 6755 8192000000 9980808864 10382917632 1068983360 11.542 99 30 60 2 1097866960 22017536 6866064
11:19:44 5418 8192000000 9988561888 10376671232 1061795360 11.474 98 75 152 0 1090151072 11525776 16829936
11:20:50 2954 8192000000 9957491936 10335825920 1018356928 11.056 98 60 120 0 1060976384 28150496 14468960
11:21:55 9216 8192000000 9933159824 10316820480 993958992 10.820 96 29 54 3 1015705440 14947568 6798880
11:23:05 6447 8192000000 9924012480 10308112384 1012991968 11.004 100 30 61 0 1046424000 25850768 7581264
11:24:11 42661 8192000000 9941192512 10344628224 1021349744 11.085 99 22 44 1 1047336080 20998256 4988080
11:25:55 4435 8192000000 9958791008 10354200576 1054619600 11.405 100 40 80 2 1075666464 11194976 9851888
11:27:05 30635 8192000000 9993487744 10382233600 1074470576 11.595 100 39 78 2 1094355600 11721984 8163040
11:28:40 17346 8192000000 10052687856 10452369408 1095290080 11.793 100 44 88 1 1152889504 48141120 9458304
11:30:06 59522 8192000000 10086223488 10494889984 1113103504 11.962 100 39 78 0 1181170032 59821376 8245152
11:32:09 9706 8192000000 10110096624 10516447232 1124809808 12.072 100 60 115 1 1222950160 85537792 12602560
11:34:08 9235 8192000000 10137542752 10550034432 1135378528 12.172 100 39 77 0 1244309936 100069904 8861504
11:35:21 2630 8192000000 10172086304 10579636224 1149849680 12.308 100 69 136 0 1282534848 117991760 14693408
11:36:32 15654 8192000000 10230646624 10645856256 1164361424 12.444 100 60 116 1 1322302304 144927520 13013360
11:37:53 15120 8192000000 10242214112 10665218048 1169530960 12.492 100 62 124 2 1349591744 165706432 14354352
11:39:11 4877 8192000000 10310268032 10724331520 1180702816 12.597 100 78 156 1 1413678944 214720960 18255168
11:40:29 8365 8192000000 10321626768 10742087680 1191847840 12.701 100 67 134 0 1423398624 215554736 15996048
11:41:42 1540 8192000000 10356386912 10764734464 1190903088 12.692 100 87 172 0 1456148896 245255424 19990384
11:42:48 4754 8192000000 10428526784 10837991424 1194913216 12.729 100 86 172 0 1517860992 303629808 19317968
11:44:00 1392 8192000000 10453613200 10872680448 1195386384 12.733 100 88 176 2 1551043040 335435600 20221056
11:45:10 2125 8192000000 10480227360 10891468800 1189362512 12.677 99 87 174 0 1570640272 360774960 20502800
11:46:23 3463 8192000000 10508770672 10940497920 1204827680 12.821 100 81 162 0 1600103136 376389952 18885504
11:47:32 3369 8192000000 10456921552 10884734976 1184615008 12.633 100 48 96 1 1556033680 359317680 12100992
11:48:46 14520 8192000000 10455558832 10891808768 1148992032 12.300 98 42 82 0 1538058224 378371664 10694528
11:50:06 9243 8192000000 10419778880 10855481344 1144191184 12.255 99 44 87 2 1505090384 350447248 10451952
11:51:17 9013 8192000000 10422501904 10883497984 1141082448 12.226 99 45 90 1 1539484160 386747776 11653936
11:52:31 39808 8192000000 10412296112 10912313344 1125056784 12.075 100 26 52 3 1535378016 402819152 7502080
11:54:18 57141 8192000000 10074640704 10661371904 1097284480 11.812 98 12 25 0 1272396160 171919024 3192656
11:57:35 43350 8192000000 10087723552 10503548928 1098247360 11.821 98 42 80 1 1133557520 27624544 7685616
11:59:20 198114 8192000000 10118558656 10561421312 1116820752 11.997 99 36 72 1 1236035760 111049232 8165776
12:03:43 4838 8192000000 10066535616 10513661952 1108414672 11.917 98 44 87 0 1155914336 38278928 9220736
12:04:48 15015 8192000000 10078575440 10502246400 1092660048 11.768 98 39 77 1 1155884208 54208656 9015504
12:06:04 61432 8192000000 10067388192 10543480832 1065579056 11.510 95 12 24 0 1157408352 88891760 2937536
12:08:12 75191 8192000000 10055351712 10492182528 1092101056 11.763 97 19 39 1 1143422336 47181264 4140016
12:10:28 40989 8192000000 10062523616 10528358400 1120788944 12.034 100 16 30 2 1174831120 51089264 2952912
12:12:10 33185 8192000000 10092740192 10565427200 1114259488 11.973 99 9 18 1 1221970400 105448112 2262800
12:13:51 18955 8192000000 10033297568 10455248896 1087283104 11.717 98 40 80 1 1112542272 16629424 8629744
12:15:11 30668 8192000000 10070516064 10530942976 1101735744 11.854 99 24 46 1 1173695632 67201328 4758560
12:16:44 54359 8192000000 10077750928 10526425088 1115301648 11.983 99 19 38 1 1171700832 52268896 4130288
12:18:44 52412 8192000000 10085632848 10527604736 1120801936 12.035 99 26 52 1 1168002608 42166256 5034416
12:20:37 17727 8192000000 10098623088 10534666240 1147901952 12.290 100 53 108 2 1193778336 34297728 11578656
12:22:08 70985 8192000000 10086914496 10525138944 1098252416 11.821 97 18 36 1 1134182112 32753872 3175824
12:24:38 136181 8192000000 10070449600 10561458176 1104437232 11.880 97 27 54 0 1176433040 66168576 5827232
12:27:55 50351 8192000000 10117095088 10572472320 1130946480 12.130 99 9 18 0 1205532768 72758912 1827376
12:29:46 33015 8192000000 10072187408 10522157056 1141311520 12.228 100 12 24 0 1180873168 37558544 2003104
12:31:21 54447 8192000000 10116013424 10564845568 1140454512 12.220 99 21 42 2 1214641344 69763056 4423776
12:33:35 35742 8192000000 10096029936 10579632128 1129001584 12.112 99 38 75 3 1231381920 94670160 7710176
12:35:13 30333 8192000000 10099853872 10540310528 1123809232 12.063 98 48 97 1 1175159968 41418048 9932688
12:36:44 44544 8192000000 10084617152 10588909568 1134568176 12.164 100 17 34 0 1210500848 72115056 3817616
12:38:30 19372 8192000000 10094819968 10524262400 1127705392 12.100 99 33 66 0 1183803424 49514176 6583856
12:39:54 39358 8192000000 10082695328 10544463872 1121739824 12.043 99 22 44 0 1172075040 46263984 4071232
12:41:40 67965 8192000000 10041857968 10525581312 1063476112 11.490 94 16 32 0 1161785696 95095264 3214320
12:43:50 142914 8192000000 10036163824 10502176768 1084306224 11.688 97 23 45 0 1136285200 46632576 5346400
12:47:18 35392 8192000000 10069374256 10536390656 1100426288 11.842 99 6 12 0 1165384272 63777776 1180208
12:48:58 43073 8192000000 10054646608 10497880064 1104404864 11.879 99 15 30 0 1149070352 40986400 3679088
12:50:41 53562 8192000000 10052441904 10478465024 1053878752 11.398 95 20 40 0 1096874528 39203104 3792672
12:52:39 33341 8192000000 10093590496 10523693056 1102029488 11.857 99 28 56 0 1151454992 43755664 5669840
12:54:21 60299 8192000000 10036028896 10493276160 1096270128 11.802 99 16 32 0 1152080400 52739792 3070480
12:56:22 40183 8192000000 10059695440 10495021056 1099766048 11.835 99 44 87 0 1147828144 39296736 8765360
12:58:02 50438 8192000000 10118998624 10552381440 1104776880 11.883 98 36 72 0 1199580608 87314944 7488784
12:59:53 105749 8192000000 10080234288 10573729792 1105468112 11.889 98 18 35 0 1180619216 71275408 3875696
13:03:16 65135 8192000000 10052867600 10508967936 1113161488 11.962 99 11 22 1 1153434752 38197840 2075424
13:05:22 87353 8192000000 10077019296 10601906176 1121214784 12.038 99 9 18 1 1197044912 73704272 2125856
13:07:50 100708 8192000000 10160473216 10623348736 1124714528 12.072 99 16 32 1 1229939952 101531216 3694208
13:10:34 81477 8192000000 10081599408 10573082624 1120177312 12.029 99 9 18 0 1213442784 91602032 1663440
13:13:14 38125 8192000000 10078608400 10518925312 1126592128 12.089 99 37 74 2 1160533616 26139488 7802000
13:14:52 37198 8192000000 10119522336 10579939328 1134063424 12.160 100 26 52 1 1186366832 46875488 5427920
13:16:39 58683 8192000000 10128294000 10606100480 1138849168 12.205 100 27 54 1 1221890240 77493504 5547568
13:18:38 131172 8192000000 10077722960 10545152000 1131620592 12.137 99 23 46 1 1171600112 35424592 4554928
13:22:23 96249 8192000000 10058678144 10532261888 1119298464 12.020 98 6 13 0 1145726560 25596624 831472
13:25:09 52555 8192000000 10079274464 10545401856 1107930992 11.913 97 11 22 0 1134077888 24184816 1962080

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-02-2021 13:28:50 to 08-02-2021 13:29:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 157 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-02-2021 13:29:06 to 08-02-2021 13:29:08.

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          224676
MON$OLDEST_ACTIVE               226353
MON$OLDEST_SNAPSHOT             226353
MON$NEXT_TRANSACTION            226353
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                       2073120
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.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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Mon Feb  8 13:26:59 2021
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Mon Feb  8 13:27:02 2021
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Mon Feb  8 13:27:04 2021
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Mon Feb  8 13:27:05 2021
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
--- end of diff output ---

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

08.02.21 13:29:09. 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-08 13:29:12 No severe PSQL-related problems occured
08.02.21 13:29:13. End of report.