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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16264948 kB
MemAvailable 31014604 kB
Buffers 0 kB
Cached 15395120 kB
SwapCached 2568 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-09 10:37:05
DTS_END                         2021-08-09 10:37:05.1500
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/51088
TRN_ID                          233762
ATT_ID                          637
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: 9109.48 27099 1093138
customer order: creation 161.79 439 19415
customer order: refuse 13.73 347 1648
order to supplier: creation 43.73 1363 5248
order to supplier: removal 3.44 115554 413
invoice (draft): creation 44.46 3404 5335
invoice (draft): removal 51.79 944 6215
invoice accept: apply 40.35 18633 4842
invoice accept: cancel 46.99 824 5639
customer reserve: creation 3769.08 181 452290
customer reserve: removal 4654.24 68 558509
realization accept: apply 79.09 194 9491
realization accept: cancel 77.68 125 9322
payment from customer: creation 56.73 128 6808
payment from customer: removal 5.48 97 658
payment to supplier: creation 46.92 129 5630
payment to supplier: removal 3.06 101 367
service: total inventory turnovers 3.97 3071 476
service: total monetary turnovers 6.07 536 728
service: aggregate perf. data 0.83 15700 100
service: refresh index statistics 0.03 380142 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2021 10:38:04 to 09-08-2021 10:38:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5304
TEST_TIME 2 4767
TEST_TIME 3 4370
TEST_TIME 4 4817
TEST_TIME 5 4912
TEST_TIME 6 5103
TEST_TIME 7 5078
TEST_TIME 8 4990
TEST_TIME 9 5391
TEST_TIME 10 5361
TEST_TIME 11 5794
TEST_TIME 12 5803
TEST_TIME 13 6384
TEST_TIME 14 6597
TEST_TIME 15 6770
TEST_TIME 16 7146
TEST_TIME 17 7671
TEST_TIME 18 7808
TEST_TIME 19 8682
TEST_TIME 20 8895
TEST_TIME 21 8798
TEST_TIME 22 8869
TEST_TIME 23 8897
TEST_TIME 24 8939
TEST_TIME 25 9350
TEST_TIME 26 11035
TEST_TIME 27 11221
TEST_TIME 28 10943
TEST_TIME 29 10604
TEST_TIME 30 10427
TEST_TIME 31 10485
TEST_TIME 32 10288
TEST_TIME 33 10261
TEST_TIME 34 10096
TEST_TIME 35 10046
TEST_TIME 36 9925
TEST_TIME 37 10120
TEST_TIME 38 9946
TEST_TIME 39 9743
TEST_TIME 40 9768
TEST_TIME 41 9818
TEST_TIME 42 9723
TEST_TIME 43 9671
TEST_TIME 44 9675
TEST_TIME 45 9783
TEST_TIME 46 9774
TEST_TIME 47 9764
TEST_TIME 48 9736
TEST_TIME 49 9845
TEST_TIME 50 9789
TEST_TIME 51 9742
TEST_TIME 52 9854
TEST_TIME 53 9728
TEST_TIME 54 9578
TEST_TIME 55 9583
TEST_TIME 56 9556
TEST_TIME 57 9504
TEST_TIME 58 9452
TEST_TIME 59 9366
TEST_TIME 60 9262
TEST_TIME 61 9238
TEST_TIME 62 9268
TEST_TIME 63 9322
TEST_TIME 64 9319
TEST_TIME 65 9257
TEST_TIME 66 9348
TEST_TIME 67 9386
TEST_TIME 68 9411
TEST_TIME 69 9439
TEST_TIME 70 9517
TEST_TIME 71 9437
TEST_TIME 72 9516
TEST_TIME 73 9603
TEST_TIME 74 9562
TEST_TIME 75 9447
TEST_TIME 76 9368
TEST_TIME 77 9346
TEST_TIME 78 9324
TEST_TIME 79 9251
TEST_TIME 80 9257
TEST_TIME 81 9216
TEST_TIME 82 9259
TEST_TIME 83 9262
TEST_TIME 84 9290
TEST_TIME 85 9287
TEST_TIME 86 9310
TEST_TIME 87 9277
TEST_TIME 88 9305
TEST_TIME 89 9273
TEST_TIME 90 9355
TEST_TIME 91 9355
TEST_TIME 92 9262
TEST_TIME 93 9198
TEST_TIME 94 9208
TEST_TIME 95 9137
TEST_TIME 96 9159
TEST_TIME 97 9140
TEST_TIME 98 9143
TEST_TIME 99 9137
TEST_TIME 100 9209
TEST_TIME 101 9282
TEST_TIME 102 9248
TEST_TIME 103 9259
TEST_TIME 104 9321
TEST_TIME 105 9311
TEST_TIME 106 9277
TEST_TIME 107 9304
TEST_TIME 108 9317
TEST_TIME 109 9297
TEST_TIME 110 9221
TEST_TIME 111 9198
TEST_TIME 112 9165
TEST_TIME 113 9177
TEST_TIME 114 9141
TEST_TIME 115 9108
TEST_TIME 116 9126
TEST_TIME 117 9122
TEST_TIME 118 9106
TEST_TIME 119 9164
TEST_TIME 120 9112

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2021 10:38:08 to 09-08-2021 10:38:11.

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 1511433 1511433 0 0.00 0 20470 7 0 0
doc_list_biud 1511432 1511432 0 0.00 0 45275 32 0 0
sp_add_invoice_to_stock 4959 4842 117 2.35 28 175757 18634 0 117
sp_cancel_adding_invoice 5639 5639 0 0.00 5 69925 825 0 0
sp_cancel_client_order 1648 1648 0 0.00 4 8860 348 0 0
sp_cancel_customer_reserve 558509 558509 0 0.00 1 45302 68 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 9893 98 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 7321 101 0 0
sp_cancel_supplier_invoice 6228 6215 13 0.20 2 17191 944 0 13
sp_cancel_supplier_order 413 413 0 0.00 5 1054597 115555 0 0
sp_cancel_write_off 9322 9322 0 0.00 1 14777 125 0 0
sp_client_order 19415 19415 0 0.00 4 65839 439 0 0
sp_customer_reserve 452290 452290 0 0.00 0 100840 181 0 0
sp_fill_shopping_cart 29973 29973 0 0.00 1 18996 46 0 0
sp_fill_shopping_cart_clo_res 452288 452288 0 0.00 0 14488 17 0 0
sp_get_clo_for_invoice 4838 4838 0 0.00 0 24444 452 0 0
sp_kill_cost_storno 15986 15986 0 0.00 0 9887 58 0 0
sp_kill_qstorno_ret_qs2qd 1132968 1132968 0 0.00 0 44834 37 0 0
sp_kill_qty_storno 596074 596074 0 0.00 0 45274 79 0 0
sp_lock_selected_doc 19800 19800 0 0.00 0 8446 4 0 0
sp_make_cost_storno 26766 26766 0 0.00 0 20456 60 0 0
sp_multiply_rows_for_pdistr 26765 26765 0 0.00 0 17895 36 0 0
sp_multiply_rows_for_qdistr 19415 19415 0 0.00 0 19322 231 0 0
sp_pay_from_customer 6808 6808 0 0.00 4 12844 129 0 0
sp_pay_to_supplier 5630 5630 0 0.00 4 17903 130 0 0
sp_payment_common 12437 12437 0 0.00 1 12842 25 0 0
sp_qd_handle_on_cancel_clo 1648 1648 0 0.00 0 8848 310 0 0
sp_qd_handle_on_invoice_upd_sts 10476 10476 0 0.00 0 20600 357 0 0
sp_qd_handle_on_reserve_upd_sts 9491 9491 0 0.00 0 15946 61 0 0
sp_reserve_write_off 9491 9491 0 0.00 7 20490 194 0 0
sp_supplier_invoice 5335 5335 0 0.00 5 85106 3405 0 0
sp_supplier_order 5248 5248 0 0.00 6 46270 1364 0 0
srv_aggregate_perf_data 101 100 1 0.99 2573 235691 15701 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 2034 153136 51198 0 0
srv_find_qd_qs_mism 1047034 1047034 0 0.00 0 14479 5 0 0
srv_make_invnt_saldo 476 476 0 0.00 55 105016 3071 0 0
srv_make_money_saldo 728 728 0 0.00 1 12726 537 0 0
srv_recalc_idx_stat 4 4 0 0.00 230760 449928 380143 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 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 4842 4842 0 0.00 0 21759 154 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 0 2132 77 0 0
t$perf-norm:sp_cancel_client_order 1648 1648 0 0.00 0 3951 11 0 0
t$perf-norm:sp_cancel_customer_reserve 620 620 0 0.00 0 2851 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 1268 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 1326 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 2798 15 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 21090 1398 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 6687 14 0 0
t$perf-norm:sp_client_order 19415 19415 0 0.00 0 22037 21 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 370 4 0 0
t$perf-norm:sp_pay_from_customer 6808 6808 0 0.00 0 4931 8 0 0
t$perf-norm:sp_pay_to_supplier 5630 5630 0 0.00 0 5837 7 0 0
t$perf-norm:sp_reserve_write_off 9491 9491 0 0.00 0 5393 9 0 0
t$perf-norm:sp_supplier_invoice 5335 5335 0 0.00 0 6452 21 0 0
t$perf-norm:sp_supplier_order 5248 5248 0 0.00 0 4972 12 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 111 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 991 18 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1162 11 0 0
t$perf-norm:srv_make_money_saldo 728 728 0 0.00 0 2965 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9908 9674 234 2.36 0 12438 17 0 234
v_all_customers 35428 35428 0 0.00 0 67 1 0 0
v_all_suppliers 29312 29312 0 0.00 0 14 1 0 0
v_all_wares 38830 38830 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 2432 11 0 0
v_cancel_client_order 3296 3296 0 0.00 0 4533 8 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 566 4 0 0
v_cancel_customer_reserve 1240 1240 0 0.00 0 3441 7 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 1038 6 0 0
v_cancel_supplier_invoice 834 808 26 3.11 0 5463 24 0 26
v_cancel_supplier_order 826 826 0 0.00 0 931 5 0 0
v_cancel_write_off 1932 1932 0 0.00 0 2460 16 0 0
v_max_id_clo_ord 5248 5248 0 0.00 0 906 1 0 0
v_max_id_clo_res 933 933 0 0.00 0 1984 11 0 0
v_max_id_ord_sup 5310 5310 0 0.00 0 2437 3 0 0
v_max_non_paid_invoice 1531 1531 0 0.00 0 142 2 0 0
v_max_non_paid_realizn 3654 3654 0 0.00 0 2650 4 0 0
v_min_id_clo_ord 5248 5248 0 0.00 0 8724 10 0 0
v_min_id_clo_res 933 933 0 0.00 0 2103 8 0 0
v_min_id_ord_sup 5310 5310 0 0.00 0 5281 8 0 0
v_min_non_paid_invoice 5629 5629 0 0.00 0 12838 16 0 0
v_min_non_paid_realizn 6808 6808 0 0.00 0 12657 21 0 0
v_our_firm 9710 9710 0 0.00 0 15 1 0 0
v_reserve_write_off 18982 18982 0 0.00 0 3223 6 0 0
x_lock_depdocs_on_canc_invoice 11450 11450 0 0.00 0 31283 119 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 25620 439 0 0
x_make_qstorno_1000_1200 5248 5248 0 0.00 2 43644 1283 0 0
x_make_qstorno_1200_2000 5335 5335 0 0.00 2 84919 3230 0 0
x_make_qstorno_2100_3300 452290 452290 0 0.00 0 100838 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2021 10:38:12 to 09-08-2021 10:38:16.

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
08:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 25572 8192000000 9733912736 10033065984 811761488 9.015 100 86 172 0 840019472 7435264 20822720
08:09:39 25768 8192000000 9770842112 10084425728 848789632 9.388 100 88 176 0 881725088 12466480 20468976
08:11:09 14697 8192000000 9810871728 10131152896 877518784 9.675 100 84 167 0 919231472 22642448 19070240
08:12:27 15311 8192000000 9873464128 10209091584 927443072 10.169 100 76 149 1 977624976 33844240 16337664
08:13:46 10317 8192000000 9932211520 10278879232 967688528 10.564 100 86 172 0 1048457696 63053072 17716096
08:15:00 5669 8192000000 9984637056 10343260160 998808688 10.867 100 74 148 1 1097174000 83438448 14926864
08:16:09 4785 8192000000 9995468656 10362724352 1031980592 11.188 100 75 150 2 1106630736 60050992 14599152
08:17:16 1944 8192000000 10044721152 10413473792 1057048848 11.428 100 87 174 0 1162857696 88210720 17598128
08:18:19 1752 8192000000 10051469136 10426552320 1071276960 11.564 100 78 152 1 1166843840 80082768 15484112
08:19:23 6622 8192000000 10019710816 10406658048 1085685936 11.702 100 43 85 1 1130741472 36099440 8956096
08:20:33 2602 8192000000 10024955488 10405408768 1085647344 11.701 100 82 164 0 1123444960 20177936 17619680
08:21:37 12472 8192000000 10021459040 10422652928 1094319968 11.784 100 32 65 3 1125538896 24378032 6840896
08:22:51 5775 8192000000 10005628304 10409476096 1097625728 11.815 100 24 47 2 1119202288 16159248 5417312
08:23:57 57839 8192000000 10032947584 10424152064 1102570384 11.862 100 54 106 3 1129957056 16053280 11333392
08:25:55 7089 8192000000 10046811792 10454364160 1096570160 11.805 99 42 85 0 1147094992 41764544 8760288
08:27:03 9517 8192000000 9995869104 10403041280 1034589776 11.213 94 49 98 2 1075191216 29308128 11293312
08:28:17 23409 8192000000 9977403248 10377265152 1048631296 11.348 97 60 122 0 1085119808 22453264 14035248
08:29:40 2395 8192000000 9982306784 10362011648 1039522480 11.260 98 67 135 1 1074660368 20570672 14567216
08:30:45 6078 8192000000 9952914032 10342322176 1018237472 11.055 98 53 102 1 1053531376 24258832 11035072
08:31:52 6188 8192000000 9952522544 10340728832 1033999456 11.207 100 34 68 1 1063843936 22693120 7151360
08:33:05 31060 8192000000 9949342800 10343739392 1022002416 11.091 98 37 76 0 1070243664 40477280 7763968
08:34:38 5045 8192000000 9976313968 10371469312 1055451584 11.413 100 46 91 1 1081055936 15990400 9613952
08:35:48 4932 8192000000 9980256096 10389037056 1069466288 11.547 100 24 48 1 1093177760 18641936 5069536
08:36:54 9542 8192000000 10006376608 10411753472 1081645056 11.663 100 40 78 2 1111756336 21210096 8901184
08:38:08 99969 8192000000 10041099264 10446311424 1109626704 11.929 100 36 70 1 1140928016 24038544 7262768
08:40:52 19883 8192000000 10122349904 10539405312 1140009024 12.216 100 51 102 1 1239080048 88636352 10434672
08:42:18 10297 8192000000 10151078064 10564501504 1136539680 12.183 99 51 100 1 1248703248 101109856 11053712
08:43:32 36953 8192000000 10193617312 10620506112 1161129136 12.414 100 55 110 0 1294387200 120357008 12901056
08:45:14 4976 8192000000 10250659856 10662055936 1169021440 12.488 100 63 124 0 1357878256 174975824 13880992
08:46:24 18061 8192000000 10276581616 10696732672 1176494592 12.557 100 54 108 0 1376430096 187599232 12336272
08:47:49 6095 8192000000 10273301440 10708172800 1171854240 12.514 99 51 98 1 1375228032 191429504 11944288
08:48:58 2297 8192000000 10323399504 10731425792 1177555968 12.567 100 92 185 0 1423081344 224361952 21163424
08:50:05 2459 8192000000 10365217408 10795384832 1186467648 12.650 100 64 127 0 1482048688 280541664 15039376
08:51:16 3743 8192000000 10353520128 10767237120 1184223008 12.630 100 73 143 1 1455173664 254761712 16188944
08:52:24 2031 8192000000 10414715904 10817970176 1167857968 12.477 99 87 173 0 1506011552 318082352 20071232
08:53:29 3776 8192000000 10373069264 10792468480 1156616768 12.372 100 61 120 2 1474098160 302725408 14755984
08:54:35 8566 8192000000 10365956128 10824888320 1150361168 12.313 100 38 76 2 1474678448 313991360 10325920
08:55:51 3852 8192000000 10388570000 10812370944 1163153424 12.433 100 80 154 2 1500358032 319135792 18068816
08:57:01 13451 8192000000 10341819600 10757697536 1144298688 12.256 99 53 106 0 1410735520 253266624 13170208
08:58:16 20084 8192000000 10379310384 10825502720 1158258448 12.387 100 35 70 0 1477695968 309794320 9643200
09:00:39 19683 8192000000 10414372480 10860044288 1140783136 12.223 99 27 54 0 1495828080 347566960 7477984
09:02:00 27061 8192000000 10417325904 10911440896 1098500288 11.823 99 30 60 0 1533138816 425592112 9046416
09:03:33 90548 8192000000 10037857504 10492805120 1053378416 11.393 95 5 10 0 1154590256 99940320 1271520
09:06:11 90001 8192000000 10054382880 10484535296 1075501408 11.605 98 36 72 1 1121476384 38587488 7387488
09:08:46 14653 8192000000 10049890272 10515668992 1106416848 11.898 100 17 32 1 1146643952 36960240 3266864
09:10:05 56920 8192000000 10090971200 10539470848 1109237920 11.925 98 21 42 0 1201213952 87914352 4061680
09:12:21 49242 8192000000 10099911392 10567413760 1114798720 11.978 99 14 28 1 1197682304 79892592 2990992
09:14:20 77022 8192000000 10103807712 10574020608 1122663536 12.052 99 19 38 0 1187369680 60440592 4265552
09:16:41 104207 8192000000 10080377120 10585055232 1122543328 12.051 99 10 20 0 1181201024 56230896 2426800
09:19:26 23315 8192000000 10070419472 10509307904 1128502128 12.107 99 26 50 0 1155806592 22220480 5083984
09:21:05 46290 8192000000 10085774544 10536198144 1144698448 12.260 100 27 54 1 1192304000 41859184 5746368
09:22:53 52696 8192000000 10091529104 10574147584 1144028032 12.253 100 22 44 1 1189209952 40560448 4621472
09:24:47 130974 8192000000 10087878304 10556891136 1126139856 12.085 98 12 24 0 1172010912 43060048 2811008
09:28:04 34322 8192000000 10136001680 10593218560 1141711024 12.232 99 28 55 0 1205669808 57798192 6160592
09:29:52 153135 8192000000 10101918688 10558627840 1137158544 12.189 99 28 56 1 1193986448 49952800 6875104
09:33:26 62577 8192000000 10084309328 10551513088 1140767584 12.223 99 9 18 0 1178759600 35994784 1997232
09:35:29 60622 8192000000 10067578464 10521137152 1115401088 11.984 97 9 18 0 1148741008 31685024 1654896
09:37:34 75470 8192000000 10139437248 10623520768 1142134976 12.236 99 20 40 0 1219671744 73217808 4318960
09:39:51 41163 8192000000 10105401760 10617147392 1154908528 12.356 100 13 26 0 1246335856 88457472 2969856
09:41:37 74676 8192000000 10132105856 10575753216 1094934256 11.790 95 20 40 3 1160747968 61356480 4457232
09:43:59 45351 8192000000 10088423152 10540834816 1103088912 11.867 97 20 40 0 1152796704 45696432 4011360
09:45:45 26265 8192000000 10110802176 10553970688 1113841600 11.969 99 36 72 0 1181914320 61068816 7003904
09:47:15 66874 8192000000 10040141840 10484940800 1059253264 11.449 94 21 42 0 1115315952 51531712 4530976
09:49:32 58093 8192000000 10060255856 10566385664 1065726304 11.511 95 16 32 1 1121416976 52390224 3300448
09:51:36 95975 8192000000 10043725184 10477518848 1104570704 11.881 100 26 52 1 1154889696 44301360 6017632
09:54:16 81671 8192000000 10039094704 10525343744 1075434080 11.604 97 15 30 0 1120055536 41286400 3335056
09:56:39 70645 8192000000 10023084752 10484412416 1072055296 11.572 97 9 18 0 1111619824 37547232 2017296
09:58:51 77574 8192000000 10086360112 10565525504 1087888848 11.723 98 19 38 1 1188806368 96571824 4345696
10:01:48 27430 8192000000 10075370240 10512027648 1122174912 12.048 100 48 98 1 1173410160 40994768 10240480
10:03:16 96076 8192000000 10105113248 10539429888 1097213824 11.811 98 21 42 0 1167657072 65942608 4500640
10:05:52 12191 8192000000 10068443056 10496024576 1118806224 12.016 100 46 92 0 1160159136 32372752 8980160
10:07:05 66248 8192000000 10110187472 10594062336 1132991952 12.150 100 18 36 1 1237699040 100713328 3993760
10:09:14 116765 8192000000 10092163872 10538057728 1129279648 12.115 99 27 54 0 1179208576 44543696 5385232
10:12:21 32007 8192000000 10110368848 10601254912 1145243904 12.265 100 7 14 0 1198307696 51743584 1320208
10:13:53 60320 8192000000 10096922864 10542673920 1130497232 12.126 99 27 53 1 1190442144 54347344 5597568
10:15:54 68916 8192000000 10145126640 10637393920 1138815760 12.204 99 18 36 1 1236863376 93839024 4208592
10:18:36 63044 8192000000 10082122960 10519543808 1133682064 12.156 99 29 56 1 1177686016 37924032 6079920
10:20:46 98263 8192000000 10072838112 10563629056 1110384256 11.936 96 23 46 1 1192055744 77164336 4507152
10:23:28 25028 8192000000 10103059536 10538733568 1125626576 12.080 98 37 74 1 1171020832 38032960 7361296
10:24:57 65357 8192000000 10093966992 10543996928 1114258544 11.973 97 32 64 0 1173611584 53061296 6291744
10:27:03 80211 8192000000 10088509440 10529968128 1129768544 12.119 99 36 72 1 1176877184 39174464 7934176
10:29:24 62726 8192000000 10104920096 10573524992 1132144368 12.142 99 6 12 0 1194363328 60936016 1282944
10:31:46 53563 8192000000 10084900976 10579079168 1141857776 12.233 100 5 10 0 1197300288 54292832 1149680
10:33:43 61618 8192000000 10096174656 10563547136 1115204480 11.982 97 22 45 0 1163442208 43615616 4622112
10:35:58 69727 8192000000 9704452768 10143567872 763501744 8.525 67 11 22 0 802130912 36766256 1862912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-08-2021 10:38:18 to 09-08-2021 10:38:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 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 09-08-2021 10:38:34 to 09-08-2021 10:38:36.

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          233658
MON$OLDEST_ACTIVE               233969
MON$OLDEST_SNAPSHOT             233969
MON$NEXT_TRANSACTION            233969
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                       2039904
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2565 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2565 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2565 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2565 Firebird 4.0/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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Mon Aug  9 10:37:11 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Mon Aug  9 10:37:13 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Mon Aug  9 10:37:15 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Mon Aug  9 10:37:17 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

09.08.21 10:38:37. 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-08-09 10:38:39 No severe PSQL-related problems occured
09.08.21 10:38:41. End of report.