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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID e0e7b46250ba4f9795ceb55e0face73f
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2020-09-25 13:42
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.00
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16455248 kB
MemAvailable 31345996 kB
Buffers 0 kB
Cached 15094068 kB
SwapCached 4820 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 2020-12-26 10:26:54
DTS_END                         2020-12-26 10:26:54.9710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(570:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41030
TRN_ID                          236859
ATT_ID                          685
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: 9140.52 26827 1096862
customer order: creation 167.06 375 20047
customer order: refuse 14.15 380 1698
order to supplier: creation 45.89 1319 5507
order to supplier: removal 3.42 124330 410
invoice (draft): creation 45.98 3633 5518
invoice (draft): removal 52.58 978 6309
invoice accept: apply 41.22 17873 4946
invoice accept: cancel 47.57 1014 5708
customer reserve: creation 3808.94 174 457073
customer reserve: removal 4621.90 73 554628
realization accept: apply 81.65 189 9798
realization accept: cancel 84.79 126 10175
payment from customer: creation 57.99 122 6959
payment from customer: removal 5.48 137 658
payment to supplier: creation 48.50 124 5820
payment to supplier: removal 3.44 93 413
service: total inventory turnovers 3.61 3986 433
service: total monetary turnovers 5.43 767 651
service: aggregate perf. data 0.89 13449 107
service: refresh index statistics 0.03 367400 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-12-2020 10:27:37 to 26-12-2020 10:27:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4817
TEST_TIME 2 5329
TEST_TIME 3 4435
TEST_TIME 4 4997
TEST_TIME 5 5001
TEST_TIME 6 5204
TEST_TIME 7 5399
TEST_TIME 8 5463
TEST_TIME 9 5367
TEST_TIME 10 5451
TEST_TIME 11 5540
TEST_TIME 12 5636
TEST_TIME 13 5736
TEST_TIME 14 5635
TEST_TIME 15 5663
TEST_TIME 16 6058
TEST_TIME 17 6294
TEST_TIME 18 6548
TEST_TIME 19 6599
TEST_TIME 20 6814
TEST_TIME 21 6812
TEST_TIME 22 6934
TEST_TIME 23 7273
TEST_TIME 24 7866
TEST_TIME 25 7807
TEST_TIME 26 8018
TEST_TIME 27 8301
TEST_TIME 28 8496
TEST_TIME 29 8679
TEST_TIME 30 8776
TEST_TIME 31 8842
TEST_TIME 32 9573
TEST_TIME 33 10041
TEST_TIME 34 10390
TEST_TIME 35 10340
TEST_TIME 36 10236
TEST_TIME 37 10136
TEST_TIME 38 10041
TEST_TIME 39 10254
TEST_TIME 40 10279
TEST_TIME 41 10342
TEST_TIME 42 10260
TEST_TIME 43 10057
TEST_TIME 44 9846
TEST_TIME 45 9698
TEST_TIME 46 9809
TEST_TIME 47 9692
TEST_TIME 48 9639
TEST_TIME 49 9653
TEST_TIME 50 9582
TEST_TIME 51 9598
TEST_TIME 52 9548
TEST_TIME 53 9494
TEST_TIME 54 9452
TEST_TIME 55 9520
TEST_TIME 56 9526
TEST_TIME 57 9515
TEST_TIME 58 9489
TEST_TIME 59 9498
TEST_TIME 60 9551
TEST_TIME 61 9652
TEST_TIME 62 9628
TEST_TIME 63 9598
TEST_TIME 64 9607
TEST_TIME 65 9713
TEST_TIME 66 9635
TEST_TIME 67 9584
TEST_TIME 68 9556
TEST_TIME 69 9430
TEST_TIME 70 9527
TEST_TIME 71 9458
TEST_TIME 72 9414
TEST_TIME 73 9327
TEST_TIME 74 9337
TEST_TIME 75 9330
TEST_TIME 76 9276
TEST_TIME 77 9304
TEST_TIME 78 9274
TEST_TIME 79 9290
TEST_TIME 80 9378
TEST_TIME 81 9384
TEST_TIME 82 9428
TEST_TIME 83 9509
TEST_TIME 84 9487
TEST_TIME 85 9483
TEST_TIME 86 9480
TEST_TIME 87 9409
TEST_TIME 88 9330
TEST_TIME 89 9318
TEST_TIME 90 9301
TEST_TIME 91 9263
TEST_TIME 92 9208
TEST_TIME 93 9196
TEST_TIME 94 9194
TEST_TIME 95 9209
TEST_TIME 96 9203
TEST_TIME 97 9203
TEST_TIME 98 9194
TEST_TIME 99 9155
TEST_TIME 100 9218
TEST_TIME 101 9214
TEST_TIME 102 9248
TEST_TIME 103 9224
TEST_TIME 104 9258
TEST_TIME 105 9172
TEST_TIME 106 9139
TEST_TIME 107 9124
TEST_TIME 108 9128
TEST_TIME 109 9107
TEST_TIME 110 9107
TEST_TIME 111 9075
TEST_TIME 112 9042
TEST_TIME 113 9017
TEST_TIME 114 8997
TEST_TIME 115 8972
TEST_TIME 116 8992
TEST_TIME 117 9028
TEST_TIME 118 9099
TEST_TIME 119 9112
TEST_TIME 120 9144

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-12-2020 10:27:41 to 26-12-2020 10:27: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 1520867 1520867 0 0.00 0 25568 7 0 0
doc_list_biud 1520866 1520866 0 0.00 0 36696 34 0 0
sp_add_invoice_to_stock 5082 4946 136 2.67 35 216937 17873 0 136
sp_cancel_adding_invoice 5708 5708 0 0.00 4 74255 1015 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 20643 380 0 0
sp_cancel_customer_reserve 554628 554628 0 0.00 1 23789 74 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 13464 137 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 9314 93 0 0
sp_cancel_supplier_invoice 6318 6309 9 0.14 1 36702 978 0 9
sp_cancel_supplier_order 410 410 0 0.00 7 1236057 124330 0 0
sp_cancel_write_off 10175 10175 0 0.00 1 6671 126 0 0
sp_client_order 20047 20047 0 0.00 4 13405 375 0 0
sp_customer_reserve 457073 457073 0 0.00 0 99307 175 0 0
sp_fill_shopping_cart 31062 31062 0 0.00 1 13380 43 0 0
sp_fill_shopping_cart_clo_res 457067 457067 0 0.00 0 24931 16 0 0
sp_get_clo_for_invoice 4939 4939 0 0.00 0 36745 370 0 0
sp_kill_cost_storno 16954 16954 0 0.00 0 14701 66 0 0
sp_kill_qstorno_ret_qs2qd 1126150 1126150 0 0.00 0 36695 40 0 0
sp_kill_qty_storno 593664 593664 0 0.00 0 36696 85 0 0
sp_lock_selected_doc 20408 20408 0 0.00 0 11510 8 0 0
sp_make_cost_storno 27516 27516 0 0.00 0 13536 60 0 0
sp_multiply_rows_for_pdistr 27515 27515 0 0.00 0 13564 35 0 0
sp_multiply_rows_for_qdistr 20047 20047 0 0.00 0 13374 217 0 0
sp_pay_from_customer 6959 6959 0 0.00 4 15714 122 0 0
sp_pay_to_supplier 5820 5820 0 0.00 5 15825 124 0 0
sp_payment_common 12779 12779 0 0.00 1 6330 21 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 20634 343 0 0
sp_qd_handle_on_invoice_upd_sts 10646 10646 0 0.00 0 23871 406 0 0
sp_qd_handle_on_reserve_upd_sts 9798 9798 0 0.00 0 25545 50 0 0
sp_reserve_write_off 9798 9798 0 0.00 7 25593 190 0 0
sp_supplier_invoice 5518 5518 0 0.00 6 135026 3634 0 0
sp_supplier_order 5507 5507 0 0.00 6 46141 1319 0 0
srv_aggregate_perf_data 107 107 0 0.00 3036 184844 13450 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1668 148889 46418 0 0
srv_find_qd_qs_mism 1050346 1050346 0 0.00 0 25567 5 0 0
srv_make_invnt_saldo 433 433 0 0.00 83 129893 3987 0 0
srv_make_money_saldo 651 651 0 0.00 1 11497 768 0 0
srv_recalc_idx_stat 4 4 0 0.00 251326 510311 367401 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4946 4946 0 0.00 0 20955 178 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 1 2550 87 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3008 12 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1282 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 687 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 798 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1788 18 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 38634 1828 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 4169 15 0 0
t$perf-norm:sp_client_order 20047 20047 0 0.00 0 8936 20 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 2618 10 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 2963 6 0 0
t$perf-norm:sp_pay_to_supplier 5820 5820 0 0.00 0 4175 9 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 11764 10 0 0
t$perf-norm:sp_supplier_invoice 5518 5518 0 0.00 0 5340 19 0 0
t$perf-norm:sp_supplier_order 5507 5507 0 0.00 0 5582 19 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 686 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 20 1 0 0
t$perf-norm:srv_make_invnt_saldo 433 433 0 0.00 0 2395 14 0 0
t$perf-norm:srv_make_money_saldo 651 651 0 0.00 0 380 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 37 11 0 0
v_add_invoice_to_stock 10148 9876 272 2.68 0 4239 17 0 272
v_all_customers 36888 36888 0 0.00 0 65 1 0 0
v_all_suppliers 30614 30614 0 0.00 0 22 1 0 0
v_all_wares 40091 40091 0 0.00 0 134 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 459 6 0 0
v_cancel_client_order 3396 3396 0 0.00 0 2202 4 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2997 12 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 3049 8 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 5885 12 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 790 13 0 18
v_cancel_supplier_order 820 820 0 0.00 0 2820 10 0 0
v_cancel_write_off 1968 1968 0 0.00 0 2107 15 0 0
v_max_id_clo_ord 5505 5505 0 0.00 0 976 2 0 0
v_max_id_clo_res 982 982 0 0.00 0 2502 18 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 3868 3 0 0
v_max_non_paid_invoice 1527 1527 0 0.00 0 345 2 0 0
v_max_non_paid_realizn 3512 3512 0 0.00 0 2952 5 0 0
v_min_id_clo_ord 5505 5505 0 0.00 0 4668 11 0 0
v_min_id_clo_res 982 982 0 0.00 0 6543 13 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 4650 7 0 0
v_min_non_paid_invoice 5820 5820 0 0.00 0 5622 14 0 0
v_min_non_paid_realizn 6959 6959 0 0.00 0 5414 17 0 0
v_our_firm 10094 10094 0 0.00 0 17 1 0 0
v_reserve_write_off 19594 19594 0 0.00 0 3366 6 0 0
x_lock_depdocs_on_canc_invoice 11595 11595 0 0.00 0 18539 115 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 17238 436 0 0
x_make_qstorno_1000_1200 5507 5507 0 0.00 2 46119 1227 0 0
x_make_qstorno_1200_2000 5518 5518 0 0.00 3 126119 3484 0 0
x_make_qstorno_2100_3300 457073 457073 0 0.00 0 99305 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-12-2020 10:27:45 to 26-12-2020 10:27: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
07:56:58 4 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:59 9874 8192000000 9698586064 9991286784 779138864 8.684 100 90 180 0 809248096 7478016 22631216
07:59:16 19079 8192000000 9745334496 10046595072 818959920 9.088 100 88 176 1 852546800 12162896 21423984
08:00:38 16626 8192000000 9787961904 10101309440 855027792 9.450 100 86 172 0 892873872 17773072 20073008
08:02:02 13752 8192000000 9849640448 10177884160 905334752 9.951 100 80 159 1 959717856 37133744 17249360
08:03:23 8653 8192000000 9901096032 10247770112 948086144 10.372 100 71 140 2 1015084864 52521216 14477504
08:04:34 5511 8192000000 9937513344 10295541760 989466848 10.776 100 74 149 1 1054302176 49786656 15048672
08:05:41 2148 8192000000 9999949936 10361073664 1027910448 11.148 100 85 170 3 1121979408 76149792 17919168
08:06:45 3840 8192000000 10021801808 10389184512 1042169600 11.286 100 84 168 0 1132441392 73902320 16369472
08:07:50 1357 8192000000 10042827760 10414604288 1068421312 11.537 100 92 186 1 1150534592 62980592 19132688
08:08:52 17154 8192000000 9976748784 10372993024 1075024160 11.600 100 42 84 1 1110064768 25643696 9396912
08:10:11 5379 8192000000 10008236480 10410131456 1084020944 11.686 100 40 79 1 1128454784 35578432 8855408
08:11:17 9956 8192000000 9994602784 10390134784 1085860656 11.703 100 27 54 0 1110216624 17955824 6400144
08:12:27 68188 8192000000 10000245872 10416361472 1090348992 11.746 100 47 91 1 1113647776 13888848 9409936
08:14:36 4636 8192000000 10016739600 10421133312 1095903648 11.799 100 32 64 2 1127749664 24467264 7378752
08:15:43 7412 8192000000 10017204736 10421751808 1095579552 11.796 100 53 110 3 1129113152 22021152 11512448
08:16:54 5706 8192000000 10016171504 10424352768 1075610240 11.606 99 55 115 1 1108837920 21454496 11773184
08:18:03 41260 8192000000 10001617328 10381848576 1028301152 11.152 95 68 135 1 1062653136 19386848 14965136
08:19:45 8091 8192000000 9959016448 10355367936 1034848528 11.215 98 38 76 2 1062329840 18650576 8830736
08:20:53 7033 8192000000 9951455872 10347511808 1032359232 11.191 99 47 93 4 1063173008 19966640 10847136
08:22:01 9638 8192000000 9950612864 10352939008 1033843760 11.205 100 33 66 1 1051882032 10350864 7687408
08:23:12 40599 8192000000 9963650096 10348335104 1045873296 11.321 99 45 90 3 1069677408 13958832 9845280
08:24:53 6139 8192000000 9976879296 10383593472 1065185456 11.506 100 41 82 2 1095194656 20544624 9464576
08:26:00 4662 8192000000 9988391424 10382442496 1074062256 11.591 100 41 79 3 1095111952 11645920 9403776
08:27:07 9669 8192000000 10014505008 10401935360 1082733584 11.674 100 55 108 2 1110459536 15681568 12044384
08:28:30 94231 8192000000 10039802896 10452488192 1099921072 11.837 100 48 96 3 1147326768 36422544 10983152
08:31:10 29444 8192000000 10070703344 10482888704 1123024400 12.056 100 47 94 3 1182495600 49804464 9666736
08:32:43 8390 8192000000 10126701360 10526351360 1134497888 12.164 100 59 116 0 1223202448 76490672 12213888
08:33:55 35803 8192000000 10143306272 10556096512 1137536288 12.192 100 50 100 1 1249332880 100796336 11000256
08:35:40 15141 8192000000 10176768896 10589966336 1142051824 12.235 99 43 86 0 1269035040 116535312 10447904
08:37:10 9422 8192000000 10203954352 10614325248 1162622048 12.428 100 50 97 3 1307347040 133678656 11046336
08:38:23 8699 8192000000 10234382576 10646724608 1157276368 12.378 99 65 130 1 1328861968 157433136 14152464
08:39:39 7962 8192000000 10268497792 10680963072 1153613264 12.343 99 63 124 2 1361209040 193672352 13923424
08:40:52 3986 8192000000 10283960832 10687365120 1154122816 12.348 100 82 159 1 1389160464 215787984 19249664
08:42:10 2154 8192000000 10322661120 10726871040 1166915328 12.468 100 87 172 0 1415388736 229187568 19285840
08:43:17 8818 8192000000 10323965168 10749788160 1165139760 12.451 100 65 129 1 1425213952 244962144 15112048
08:44:34 2141 8192000000 10350910976 10759024640 1159235536 12.396 100 81 162 0 1446427776 269046800 18145440
08:45:48 2619 8192000000 10335643696 10745544704 1162630928 12.428 100 55 109 2 1439886912 264791472 12464512
08:46:57 6519 8192000000 10354858384 10762321920 1154378720 12.351 100 56 112 1 1458386864 290817392 13190752
08:48:08 3458 8192000000 10411342544 10809839616 1159326368 12.397 100 82 164 0 1511950288 333503120 19120800
08:49:18 1664 8192000000 10439952032 10845622272 1167382720 12.472 100 83 167 2 1541211360 353250064 20578576
08:50:22 12438 8192000000 10391253584 10813775872 1149356080 12.303 100 43 85 0 1494746864 334222560 11168224
08:51:39 7847 8192000000 10387564624 10802831360 1151403728 12.323 100 57 110 0 1484185040 319208944 13572368
08:52:49 11357 8192000000 10381752848 10808594432 1142116080 12.235 99 44 87 1 1468970064 316286912 10567072
08:54:05 17237 8192000000 10325887440 10814914560 1134975248 12.168 99 25 51 2 1439502432 297848656 6678528
08:55:26 6536 8192000000 10384203088 10808844288 1152267392 12.331 100 40 80 3 1481863024 319883888 9711744
08:57:31 23404 8192000000 10376659072 10880389120 1142078320 12.235 100 24 48 0 1516824864 368234512 6512032
08:58:56 16195 8192000000 10198956384 10693148672 1145450880 12.267 100 24 48 1 1303680720 152662048 5567792
09:00:14 51994 8192000000 10056912128 10490101760 1099402416 11.832 97 18 38 1 1158372352 54174336 4795600
09:02:07 47329 8192000000 10056185360 10484592640 1097275696 11.812 98 31 60 1 1138896896 34881232 6739968
09:03:55 85628 8192000000 10072783664 10580713472 1113822736 11.969 99 18 36 3 1186443568 68583344 4037488
09:06:21 85512 8192000000 10091142896 10590707712 1113233536 11.963 98 9 17 1 1162688896 47760144 1695216
09:08:56 148889 8192000000 10123480144 10569924608 1125548064 12.079 98 35 70 2 1180073952 47067504 7458384
09:12:35 50116 8192000000 10095659936 10551951360 1132008928 12.140 98 24 48 2 1198302112 60863632 5429552
09:14:28 32189 8192000000 10126058128 10585513984 1125695200 12.081 98 20 40 1 1214018304 83788992 4534112
09:16:08 73717 8192000000 10105535008 10575990784 1121663632 12.043 98 13 26 2 1181457008 56596816 3196560
09:18:24 53361 8192000000 10109421600 10549018624 1120959504 12.036 98 11 22 0 1199491792 76029520 2502768
09:20:18 67525 8192000000 10101433888 10604687360 1121073936 12.037 98 16 32 2 1202789232 78122096 3593200
09:22:26 52510 8192000000 10055027104 10538995712 1102313120 11.860 97 14 28 1 1136543392 30975376 3254896
09:24:19 19500 8192000000 10067818016 10500755456 1117340064 12.002 99 44 88 1 1151990592 25425504 9225024
09:25:40 50141 8192000000 10103311248 10576826368 1106430912 11.899 97 18 36 1 1171102320 60855536 3815872
09:27:35 106663 8192000000 10059988608 10548006912 1078378800 11.632 95 8 16 0 1159244256 79025808 1839648
09:30:27 61630 8192000000 10072939056 10564640768 1112486960 11.956 98 14 28 3 1179444432 63887936 3069536
09:32:31 31373 8192000000 10068087616 10499964928 1097315776 11.812 98 26 52 2 1138385632 35578160 5491696
09:34:04 128505 8192000000 10094844464 10581082112 1113364976 11.964 99 24 48 3 1189717280 71266928 5085376
09:37:21 34727 8192000000 10044527744 10536992768 1110654112 11.939 99 7 14 0 1146760752 34940880 1165760
09:38:58 47570 8192000000 10081767568 10543206400 1129359136 12.115 100 10 20 2 1173344400 42178896 1806368
09:41:24 64601 8192000000 10087749440 10517221376 1068835568 11.541 94 15 30 2 1123313312 51498416 2979328
09:43:29 57887 8192000000 10154905024 10599153664 1135860192 12.177 99 31 62 1 1222942816 79644912 7437712
09:45:29 44280 8192000000 10161899312 10629345280 1099971968 11.837 96 25 50 1 1240057888 134490288 5595632
09:47:18 47965 8192000000 10130506528 10627915776 1117959152 12.008 98 27 53 0 1242255456 118420784 5875520
09:49:15 61228 8192000000 10080391888 10497519616 1113865872 11.969 98 38 73 2 1149755936 28843456 7046608
09:51:17 32145 8192000000 10091297984 10526179328 1107226448 11.906 98 37 74 2 1162296000 47941232 7128320
09:52:51 106103 8192000000 10065375632 10520178688 1128614768 12.108 100 30 60 1 1189565472 54613936 6336768
09:55:50 72622 8192000000 10044808880 10517987328 1103063216 11.867 97 12 24 0 1155073824 49280704 2729904
09:58:17 50391 8192000000 10102215920 10580103168 1135337648 12.172 100 6 12 0 1202033600 65654736 1041216
10:00:17 64602 8192000000 10080784592 10552946688 1111761424 11.949 97 13 26 2 1162976576 48228304 2986848
10:02:28 45341 8192000000 10056882576 10483462144 1090087856 11.743 97 24 46 2 1114544944 19670224 4786864
10:04:19 54362 8192000000 10078286624 10511630336 1133553840 12.155 100 30 60 0 1175131600 35140928 6436832
10:06:21 134100 8192000000 10105088176 10561126400 1084811328 11.693 95 18 36 0 1120863840 32409392 3643120
10:09:36 111510 8192000000 10130087008 10623946752 1123020752 12.056 98 13 26 0 1210485296 84252656 3211888
10:12:54 77787 8192000000 10102365280 10558124032 1138220896 12.199 99 17 33 1 1196258992 54379376 3658720
10:15:57 89888 8192000000 10066836576 10524069888 1088334848 11.727 95 11 22 0 1119679376 29290832 2053696
10:18:38 40495 8192000000 10104981504 10550927360 1124668160 12.071 99 44 89 2 1187247584 53062864 9516560
10:20:20 51859 8192000000 10144353120 10587627520 1107668256 11.910 97 22 44 0 1203131088 90273760 5189072
10:22:32 65497 8192000000 10138813440 10597441536 1115027984 11.980 98 15 30 0 1234423344 115952432 3442928
10:24:39 77452 8192000000 10083291296 10504781824 1110899888 11.941 99 18 33 2 1160386192 45633968 3852336

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-12-2020 10:27:51 to 26-12-2020 10:28:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-12-2020 10:28:07 to 26-12-2020 10: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          236293
MON$OLDEST_ACTIVE               237200
MON$OLDEST_SNAPSHOT             237200
MON$NEXT_TRANSACTION            237200
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                       2129920
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             801
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2307 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2307 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.2307 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Sat Dec 26 10:27:06 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

26.12.20 10: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
2020-12-26 10:28:12 No severe PSQL-related problems occured
26.12.20 10:28:14. End of report.