Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.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 16525884 kB
MemAvailable 31372068 kB
Buffers 0 kB
Cached 15084656 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-31 10:26:51
DTS_END                         2020-12-31 10:26:51.4610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34004
TRN_ID                          227869
ATT_ID                          578
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: 9206.25 19811 1104750
customer order: creation 165.33 396 19839
customer order: refuse 14.07 281 1688
order to supplier: creation 43.39 1398 5207
order to supplier: removal 3.28 116905 394
invoice (draft): creation 43.89 3276 5267
invoice (draft): removal 52.29 887 6275
invoice accept: apply 40.09 14535 4811
invoice accept: cancel 47.54 797 5705
customer reserve: creation 3775.33 139 453039
customer reserve: removal 4738.69 65 568643
realization accept: apply 79.46 151 9535
realization accept: cancel 79.78 124 9573
payment from customer: creation 56.56 94 6787
payment from customer: removal 5.67 85 680
payment to supplier: creation 47.31 103 5677
payment to supplier: removal 2.94 84 353
service: total inventory turnovers 3.81 3093 457
service: total monetary turnovers 5.96 406 715
service: aggregate perf. data 0.84 14714 101
service: refresh index statistics 0.03 238679 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-12-2020 10:28:18 to 31-12-2020 10:28:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2729
TEST_TIME 2 2673
TEST_TIME 3 2891
TEST_TIME 4 2905
TEST_TIME 5 3141
TEST_TIME 6 3666
TEST_TIME 7 3684
TEST_TIME 8 3824
TEST_TIME 9 4133
TEST_TIME 10 4504
TEST_TIME 11 4479
TEST_TIME 12 4607
TEST_TIME 13 5222
TEST_TIME 14 5384
TEST_TIME 15 5502
TEST_TIME 16 5991
TEST_TIME 17 6197
TEST_TIME 18 6317
TEST_TIME 19 6816
TEST_TIME 20 7233
TEST_TIME 21 7562
TEST_TIME 22 7948
TEST_TIME 23 8344
TEST_TIME 24 8809
TEST_TIME 25 9923
TEST_TIME 26 10088
TEST_TIME 27 10247
TEST_TIME 28 10490
TEST_TIME 29 10582
TEST_TIME 30 10447
TEST_TIME 31 10397
TEST_TIME 32 10376
TEST_TIME 33 10427
TEST_TIME 34 10686
TEST_TIME 35 10641
TEST_TIME 36 10413
TEST_TIME 37 10700
TEST_TIME 38 10625
TEST_TIME 39 10355
TEST_TIME 40 10166
TEST_TIME 41 10071
TEST_TIME 42 9967
TEST_TIME 43 9959
TEST_TIME 44 10002
TEST_TIME 45 9985
TEST_TIME 46 9880
TEST_TIME 47 9787
TEST_TIME 48 9865
TEST_TIME 49 9792
TEST_TIME 50 9872
TEST_TIME 51 9796
TEST_TIME 52 9830
TEST_TIME 53 9905
TEST_TIME 54 9913
TEST_TIME 55 9909
TEST_TIME 56 9947
TEST_TIME 57 9955
TEST_TIME 58 10068
TEST_TIME 59 10087
TEST_TIME 60 10061
TEST_TIME 61 9919
TEST_TIME 62 9808
TEST_TIME 63 9873
TEST_TIME 64 9812
TEST_TIME 65 9725
TEST_TIME 66 9700
TEST_TIME 67 9658
TEST_TIME 68 9691
TEST_TIME 69 9654
TEST_TIME 70 9664
TEST_TIME 71 9607
TEST_TIME 72 9590
TEST_TIME 73 9639
TEST_TIME 74 9705
TEST_TIME 75 9715
TEST_TIME 76 9762
TEST_TIME 77 9835
TEST_TIME 78 9722
TEST_TIME 79 9662
TEST_TIME 80 9591
TEST_TIME 81 9538
TEST_TIME 82 9565
TEST_TIME 83 9546
TEST_TIME 84 9510
TEST_TIME 85 9492
TEST_TIME 86 9453
TEST_TIME 87 9474
TEST_TIME 88 9550
TEST_TIME 89 9567
TEST_TIME 90 9543
TEST_TIME 91 9549
TEST_TIME 92 9626
TEST_TIME 93 9668
TEST_TIME 94 9647
TEST_TIME 95 9637
TEST_TIME 96 9543
TEST_TIME 97 9542
TEST_TIME 98 9526
TEST_TIME 99 9512
TEST_TIME 100 9480
TEST_TIME 101 9449
TEST_TIME 102 9433
TEST_TIME 103 9412
TEST_TIME 104 9395
TEST_TIME 105 9408
TEST_TIME 106 9426
TEST_TIME 107 9404
TEST_TIME 108 9401
TEST_TIME 109 9410
TEST_TIME 110 9444
TEST_TIME 111 9464
TEST_TIME 112 9500
TEST_TIME 113 9420
TEST_TIME 114 9399
TEST_TIME 115 9356
TEST_TIME 116 9305
TEST_TIME 117 9262
TEST_TIME 118 9226
TEST_TIME 119 9242
TEST_TIME 120 9208

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2020 10:28:22 to 31-12-2020 10:28:25.

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 1526347 1526347 0 0.00 0 50308 5 0 0
doc_list_biud 1526347 1526347 0 0.00 0 82300 30 0 0
sp_add_invoice_to_stock 4883 4811 72 1.47 35 217188 14535 0 72
sp_cancel_adding_invoice 5705 5705 0 0.00 7 99536 797 0 0
sp_cancel_client_order 1688 1688 0 0.00 5 9712 281 0 0
sp_cancel_customer_reserve 568643 568643 0 0.00 1 28960 65 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 11400 85 0 0
sp_cancel_pay_to_supplier 353 353 0 0.00 3 2101 85 0 0
sp_cancel_supplier_invoice 6283 6275 8 0.12 3 82307 888 0 8
sp_cancel_supplier_order 394 394 0 0.00 11 1277446 116905 0 0
sp_cancel_write_off 9573 9573 0 0.00 1 50622 125 0 0
sp_client_order 19839 19839 0 0.00 4 26149 396 0 0
sp_customer_reserve 453039 453039 0 0.00 0 60329 140 0 0
sp_fill_shopping_cart 30291 30291 0 0.00 1 25383 44 0 0
sp_fill_shopping_cart_clo_res 453034 453034 0 0.00 0 26525 16 0 0
sp_get_clo_for_invoice 4806 4806 0 0.00 0 28108 383 0 0
sp_kill_cost_storno 16311 16311 0 0.00 0 9090 56 0 0
sp_kill_qstorno_ret_qs2qd 1153528 1153528 0 0.00 0 82299 35 0 0
sp_kill_qty_storno 606619 606619 0 0.00 0 82300 75 0 0
sp_lock_selected_doc 19818 19818 0 0.00 0 7996 4 0 0
sp_make_cost_storno 26805 26805 0 0.00 0 13047 49 0 0
sp_multiply_rows_for_pdistr 26805 26805 0 0.00 0 7323 26 0 0
sp_multiply_rows_for_qdistr 19839 19839 0 0.00 0 26127 225 0 0
sp_pay_from_customer 6787 6787 0 0.00 4 9403 94 0 0
sp_pay_to_supplier 5677 5677 0 0.00 4 18896 104 0 0
sp_payment_common 12464 12464 0 0.00 1 9510 16 0 0
sp_qd_handle_on_cancel_clo 1688 1688 0 0.00 0 9704 253 0 0
sp_qd_handle_on_invoice_upd_sts 10511 10511 0 0.00 0 47944 344 0 0
sp_qd_handle_on_reserve_upd_sts 9535 9535 0 0.00 0 11363 47 0 0
sp_reserve_write_off 9535 9535 0 0.00 6 16040 152 0 0
sp_supplier_invoice 5267 5267 0 0.00 6 87360 3277 0 0
sp_supplier_order 5207 5207 0 0.00 6 45831 1399 0 0
srv_aggregate_perf_data 101 101 0 0.00 3224 179049 14714 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2371 154318 51780 0 0
srv_find_qd_qs_mism 1059746 1059746 0 0.00 0 50308 4 0 0
srv_make_invnt_saldo 457 457 0 0.00 47 96360 3093 0 0
srv_make_money_saldo 715 715 0 0.00 1 15906 407 0 0
srv_recalc_idx_stat 4 4 0 0.00 98399 341460 238680 0 0
t$perf-abend:sp_add_invoice_to_stock 72 72 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4811 4811 0 0.00 0 6565 144 0 0
t$perf-norm:sp_cancel_adding_invoice 372 372 0 0.00 0 8497 170 0 0
t$perf-norm:sp_cancel_client_order 1688 1688 0 0.00 0 4106 15 0 0
t$perf-norm:sp_cancel_customer_reserve 628 628 0 0.00 0 4238 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 539 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 353 353 0 0.00 0 4608 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 353 353 0 0.00 0 1149 17 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 14478 1483 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3449 19 0 0
t$perf-norm:sp_client_order 19839 19839 0 0.00 0 23037 21 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 5907 10 0 0
t$perf-norm:sp_pay_from_customer 6787 6787 0 0.00 0 17408 16 0 0
t$perf-norm:sp_pay_to_supplier 5677 5677 0 0.00 0 7458 16 0 0
t$perf-norm:sp_reserve_write_off 9535 9535 0 0.00 0 27263 16 0 0
t$perf-norm:sp_supplier_invoice 5267 5267 0 0.00 0 5657 15 0 0
t$perf-norm:sp_supplier_order 5207 5207 0 0.00 0 5520 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 337 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 764 13 0 0
t$perf-norm:srv_make_invnt_saldo 457 457 0 0.00 0 4669 35 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 2958 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9752 9608 144 1.47 0 4126 15 0 144
v_all_customers 36488 36488 0 0.00 0 22 1 0 0
v_all_suppliers 29342 29342 0 0.00 0 117 1 0 0
v_all_wares 39672 39672 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 744 744 0 0.00 0 1140 7 0 0
v_cancel_client_order 3376 3376 0 0.00 0 3207 4 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3894 7 0 0
v_cancel_customer_reserve 1256 1256 0 0.00 0 180 3 0 0
v_cancel_payment_to_supplier 706 706 0 0.00 0 2037 6 0 0
v_cancel_supplier_invoice 722 706 16 2.21 0 679 13 0 16
v_cancel_supplier_order 788 788 0 0.00 0 231 3 0 0
v_cancel_write_off 2020 2020 0 0.00 0 4556 13 0 0
v_max_id_clo_ord 5202 5202 0 0.00 0 250 1 0 0
v_max_id_clo_res 928 928 0 0.00 0 11423 39 0 0
v_max_id_ord_sup 5253 5253 0 0.00 0 6585 8 0 0
v_max_non_paid_invoice 1461 1461 0 0.00 0 519 2 0 0
v_max_non_paid_realizn 3456 3456 0 0.00 0 1678 3 0 0
v_min_id_clo_ord 5202 5202 0 0.00 0 3551 6 0 0
v_min_id_clo_res 928 928 0 0.00 0 5902 18 0 0
v_min_id_ord_sup 5253 5253 0 0.00 0 7601 6 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 9504 10 0 0
v_min_non_paid_realizn 6787 6787 0 0.00 0 4706 9 0 0
v_our_firm 9846 9846 0 0.00 0 11 1 0 0
v_reserve_write_off 19070 19070 0 0.00 0 7228 5 0 0
x_lock_depdocs_on_canc_invoice 11627 11627 0 0.00 0 11748 99 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 14352 301 0 0
x_make_qstorno_1000_1200 5207 5207 0 0.00 2 45747 1330 0 0
x_make_qstorno_1200_2000 5267 5267 0 0.00 2 87352 3097 0 0
x_make_qstorno_2100_3300 453039 453039 0 0.00 0 57997 122 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-12-2020 10:28:26 to 31-12-2020 10:28:30.

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:55 2 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:57:56 25078 8192000000 9713008784 10006880256 794625216 8.842 100 90 180 1 824457952 7773376 22059360
07:59:30 28183 8192000000 9756966752 10057986048 829732544 9.197 100 92 184 0 864654944 12507904 22414496
08:01:02 16678 8192000000 9803759488 10121027584 864682736 9.547 100 79 158 1 907458928 24745152 18031040
08:02:22 13865 8192000000 9867022272 10199846912 917749584 10.074 100 75 150 1 971418048 37085984 16582480
08:03:41 6396 8192000000 9916321856 10262601728 964029200 10.528 100 78 156 5 1025408944 44798032 16581712
08:04:54 3708 8192000000 9999827712 10349899776 1005460304 10.931 100 89 179 1 1107277920 83352832 18464784
08:06:00 3661 8192000000 9995581152 10359189504 1026450816 11.134 100 81 162 1 1104183872 61278384 16454672
08:07:05 1994 8192000000 10015203488 10383302656 1047793056 11.340 100 94 186 1 1132500160 65124480 19582624
08:08:08 1854 8192000000 10052161056 10421088256 1062368640 11.479 100 92 183 4 1161569248 80445712 18754896
08:09:11 1437 8192000000 10015129904 10386153472 1069470880 11.547 100 92 184 2 1123254544 33812272 19971392
08:10:13 7170 8192000000 9991963968 10376892416 1074097088 11.591 100 49 95 1 1102593600 18415216 10081296
08:11:25 11217 8192000000 9988473888 10390769664 1081492832 11.662 100 32 63 4 1106247104 17720224 7034048
08:12:39 65287 8192000000 10002734048 10395492352 1088798752 11.731 100 52 105 1 1114838928 15408256 10631920
08:14:44 5393 8192000000 10010678880 10406170624 1093408192 11.775 100 46 88 1 1129348656 25360928 10579536
08:15:53 4282 8192000000 10015509328 10410008576 1099503888 11.833 100 49 96 2 1127918560 17768016 10646656
08:17:00 10656 8192000000 10024035584 10435362816 1087238928 11.716 99 23 46 0 1119596992 27075552 5282512
08:18:13 30659 8192000000 9985879952 10389929984 1015362752 11.027 93 25 50 1 1046611696 26288336 4960608
08:19:44 6742 8192000000 9943062848 10316845056 997407632 10.853 96 36 73 0 1023294864 17339280 8547952
08:20:54 7615 8192000000 9933761072 10306297856 1000432784 10.883 98 34 68 2 1025125360 17075616 7616960
08:22:04 4931 8192000000 9955461152 10341883904 1036061936 11.227 100 47 94 3 1070397248 24153232 10182080
08:23:12 24499 8192000000 9956987680 10360922112 1030009232 11.169 99 22 44 0 1052899248 17966960 4923056
08:24:36 4860 8192000000 9968147904 10351046656 1050562688 11.366 100 32 66 1 1079156400 21409296 7184416
08:25:42 5984 8192000000 9972741168 10365837312 1060445584 11.461 100 42 85 3 1082928336 12617728 9865024
08:26:49 42220 8192000000 9998632336 10392281088 1085513776 11.700 100 35 71 1 1108079744 14651520 7914448
08:28:34 79141 8192000000 10043527024 10453766144 1101330976 11.850 100 24 48 2 1143226368 36739456 5155936
08:30:55 13174 8192000000 10082636000 10488479744 1124167072 12.066 100 39 78 1 1187204784 54263792 8773920
08:32:22 13152 8192000000 10146643872 10552946688 1138377712 12.200 100 55 107 1 1250717392 100785168 11554512
08:33:37 9483 8192000000 10199486160 10603606016 1152567904 12.334 100 57 111 0 1308441968 143744400 12129664
08:34:57 16639 8192000000 10271523296 10677899264 1165905904 12.459 100 60 120 1 1367853968 188075792 13872272
08:36:22 24364 8192000000 10284988832 10710577152 1174015600 12.534 100 53 106 1 1399656496 212911200 12729696
08:38:03 9289 8192000000 10349708800 10772860928 1189859248 12.682 100 66 132 0 1457225968 251089248 16277472
08:39:20 3849 8192000000 10316107216 10736250880 1175112816 12.545 99 55 110 1 1410521792 222542320 12866656
08:40:27 4367 8192000000 10374528256 10787266560 1194258080 12.723 100 66 132 2 1473457712 263230752 15968880
08:41:36 5750 8192000000 10401445888 10823151616 1183102192 12.619 100 64 126 0 1507472720 308537680 15832848
08:42:46 9265 8192000000 10425853632 10877038592 1175713328 12.550 99 40 80 1 1515448912 329425168 10310416
08:44:08 2370 8192000000 10424750976 10837254144 1161538464 12.418 100 72 144 0 1526377744 347882272 16957008
08:45:15 8388 8192000000 10439842960 10866692096 1142025744 12.235 98 41 82 0 1518711728 365837568 10848416
08:46:31 2593 8192000000 10378477104 10786078720 1138907744 12.205 99 69 139 0 1479715168 323252016 17555408
08:47:38 18709 8192000000 10402523056 10835107840 1137119360 12.188 99 33 66 0 1509904432 363578448 9206624
08:49:17 19660 8192000000 10308223456 10756534272 1116816736 11.997 98 32 64 1 1401387776 275935744 8635296
08:51:07 35770 8192000000 10109059328 10602532864 1079746048 11.645 97 25 50 2 1283876064 197956608 6173408
08:52:53 57156 8192000000 10105128800 10600628224 1076773600 11.617 97 10 20 1 1198554048 119281568 2498880
08:54:51 98403 8192000000 10052384640 10507395072 1077290944 11.622 98 9 17 2 1151641920 72355504 1995472
08:57:30 76543 8192000000 10042786864 10468126720 1047850640 11.340 94 21 42 3 1106380048 54113088 4416320
09:00:05 56585 8192000000 10048072800 10489483264 1107799648 11.912 99 13 26 0 1160124848 49751840 2573360
09:02:04 58304 8192000000 10127228432 10542854144 1099175024 11.830 97 30 59 1 1187526144 81826016 6525104
09:04:09 154318 8192000000 10077508928 10501406720 1111408928 11.946 98 29 58 2 1143948464 26421488 6118048
09:07:44 31232 8192000000 10117120608 10551197696 1132664528 12.146 100 41 83 1 1215827024 74440448 8722048
09:09:16 51659 8192000000 10112459360 10567176192 1135634832 12.174 100 12 24 2 1201852800 63471840 2746128
09:11:09 36686 8192000000 10057223024 10534006784 1112050880 11.952 98 20 41 2 1170679104 54058320 4569904
09:12:50 48114 8192000000 10104273024 10555080704 1135581008 12.174 100 12 24 0 1205211184 66989776 2640400
09:14:40 46605 8192000000 10098038336 10573418496 1133988528 12.159 100 3 6 0 1185835344 51433264 413552
09:16:28 52706 8192000000 10088675984 10534506496 1152861936 12.336 100 14 28 1 1213605488 57774992 2968560
09:18:22 58913 8192000000 10090011216 10572767232 1127089760 12.094 98 16 32 0 1195008192 64412464 3505968
09:20:22 72595 8192000000 10116977328 10573963264 1137335936 12.190 99 29 58 2 1188582928 45513760 5733232
09:22:38 51902 8192000000 10160116160 10605211648 1157193760 12.377 100 26 52 2 1239965376 77439008 5332608
09:24:39 73066 8192000000 10113590016 10551734272 1116195552 11.991 96 22 44 2 1183643600 63325712 4122336
09:26:52 104086 8192000000 10125247328 10561613824 1131991808 12.140 99 34 67 1 1214931568 75832224 7107536
09:29:48 44576 8192000000 10091162928 10575249408 1140284880 12.218 99 6 12 0 1197727536 56432176 1010480
09:32:58 67300 8192000000 10106545936 10549501952 1124145680 12.066 98 20 40 0 1169777456 41606032 4025744
09:35:06 39625 8192000000 10073671808 10513612800 1099683840 11.835 97 18 36 0 1140083264 37040944 3358480
09:36:51 28123 8192000000 10084040912 10513993728 1088927408 11.732 96 21 43 1 1147663232 54254688 4481136
09:38:22 92102 8192000000 10108603792 10588418048 1092146960 11.763 98 20 39 3 1195717536 99264368 4306208
09:40:55 35999 8192000000 10081894320 10516467712 1067445280 11.528 97 44 88 2 1121889792 45335472 9109040
09:43:09 78624 8192000000 10046665824 10554241024 1051483552 11.375 95 11 20 1 1084748768 31156160 2109056
09:45:30 61818 8192000000 10047467072 10510323712 1081506688 11.662 98 16 30 2 1112810080 28323744 2979648
09:47:39 62213 8192000000 10072266400 10547531776 1085486304 11.700 98 4 8 0 1146940992 60591920 862768
09:49:46 81581 8192000000 10055956928 10500063232 1095236512 11.792 99 13 26 0 1127423280 29069648 3117120
09:52:19 73537 8192000000 10122373440 10589343744 1117253664 12.001 99 29 56 1 1186830448 63763328 5813456
09:54:34 101342 8192000000 10064169728 10530914304 1103811392 11.874 98 10 20 0 1148114192 42185520 2117280
09:57:16 47009 8192000000 10101713312 10577944576 1126377456 12.087 99 17 32 2 1197967616 68462960 3127200
09:59:04 101994 8192000000 10125649632 10596036608 1086732800 11.712 95 11 23 2 1137896224 49243712 1919712
10:01:46 98368 8192000000 10117743136 10627403776 1139737296 12.213 100 12 24 0 1247547024 104994784 2814944
10:04:30 47240 8192000000 10100392064 10644164608 1113753504 11.968 99 2 4 0 1189263088 74934640 574944
10:07:17 31662 8192000000 10057144768 10499375104 1119073808 12.018 99 17 33 1 1137012224 14284464 3653952
10:08:50 43379 8192000000 10095846960 10538176512 1128431504 12.107 99 27 54 2 1170691680 36752608 5507568
10:10:34 63194 8192000000 10097894800 10554929152 1122934688 12.055 98 20 40 1 1213486736 86047920 4504128
10:12:42 80899 8192000000 10109593904 10559938560 1134973712 12.168 99 27 54 1 1194201936 53526784 5701440
10:15:03 46915 8192000000 10133746640 10593050624 1136505248 12.183 99 28 56 5 1210643008 68342928 5794832
10:16:51 73941 8192000000 10115800496 10579247104 1108292240 11.916 97 9 18 0 1169764352 59469312 2002800
10:19:06 118459 8192000000 10054006032 10529021952 1116876256 11.997 99 12 24 0 1144462080 24950656 2635168
10:22:11 75306 8192000000 10064084576 10537369600 1103582544 11.872 97 12 23 0 1144552080 38280112 2689424
10:25:26 64473 8192000000 10065072864 10510598144 1108089568 11.914 98 14 26 3 1137728704 26576704 3062432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2020 10:28:32 to 31-12-2020 10:28:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 72 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-12-2020 10:28:48 to 31-12-2020 10:28:50.

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          227764
MON$OLDEST_ACTIVE               229091
MON$OLDEST_SNAPSHOT             229091
MON$NEXT_TRANSACTION            229091
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                       2121984
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             783
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2313 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2313 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.2313 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Dec 31 10:26:58 2020
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Dec 31 10:26:59 2020
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Dec 31 10:26:59 2020
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
--- end of diff output ---

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

31.12.20 10:28:51. 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-31 10:28:53 No severe PSQL-related problems occured
31.12.20 10:28:55. End of report.