Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.11.2021 04: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16459176 kB
MemAvailable 31513440 kB
Buffers 20 kB
Cached 15139760 kB
SwapCached 0 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-11-24 04:37:09
DTS_END                         2021-11-24 04:37:09.2700
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/53464
TRN_ID                          237796
ATT_ID                          684
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: 9438.97 18907 1132676
customer order: creation 174.13 433 20896
customer order: refuse 14.83 281 1780
order to supplier: creation 45.89 1303 5507
order to supplier: removal 3.33 126544 400
invoice (draft): creation 46.64 3202 5597
invoice (draft): removal 53.83 1003 6460
invoice accept: apply 42.30 17591 5076
invoice accept: cancel 48.58 780 5830
customer reserve: creation 3927.93 173 471351
customer reserve: removal 4783.02 70 573962
realization accept: apply 84.61 192 10153
realization accept: cancel 84.52 117 10142
payment from customer: creation 61.07 112 7328
payment from customer: removal 5.62 85 674
payment to supplier: creation 48.25 133 5790
payment to supplier: removal 3.64 89 437
service: total inventory turnovers 3.98 3751 477
service: total monetary turnovers 5.87 608 704
service: aggregate perf. data 0.89 17624 107
service: refresh index statistics 0.04 204045 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-11-2021 04:37:55 to 24-11-2021 04:37:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9552
TEST_TIME 2 6406
TEST_TIME 3 6367
TEST_TIME 4 6391
TEST_TIME 5 6413
TEST_TIME 6 6463
TEST_TIME 7 6547
TEST_TIME 8 6295
TEST_TIME 9 6578
TEST_TIME 10 6652
TEST_TIME 11 6863
TEST_TIME 12 7168
TEST_TIME 13 7437
TEST_TIME 14 7750
TEST_TIME 15 8007
TEST_TIME 16 8085
TEST_TIME 17 8272
TEST_TIME 18 9059
TEST_TIME 19 8856
TEST_TIME 20 8898
TEST_TIME 21 9135
TEST_TIME 22 9145
TEST_TIME 23 9428
TEST_TIME 24 9601
TEST_TIME 25 9831
TEST_TIME 26 10071
TEST_TIME 27 12129
TEST_TIME 28 11869
TEST_TIME 29 12103
TEST_TIME 30 11948
TEST_TIME 31 11729
TEST_TIME 32 11496
TEST_TIME 33 11307
TEST_TIME 34 11264
TEST_TIME 35 11133
TEST_TIME 36 10996
TEST_TIME 37 10910
TEST_TIME 38 10861
TEST_TIME 39 10811
TEST_TIME 40 10721
TEST_TIME 41 10617
TEST_TIME 42 10811
TEST_TIME 43 10763
TEST_TIME 44 10594
TEST_TIME 45 10511
TEST_TIME 46 10336
TEST_TIME 47 10294
TEST_TIME 48 10187
TEST_TIME 49 10049
TEST_TIME 50 10029
TEST_TIME 51 9926
TEST_TIME 52 9820
TEST_TIME 53 9721
TEST_TIME 54 9785
TEST_TIME 55 9754
TEST_TIME 56 9742
TEST_TIME 57 9704
TEST_TIME 58 9811
TEST_TIME 59 10100
TEST_TIME 60 10054
TEST_TIME 61 9986
TEST_TIME 62 10061
TEST_TIME 63 10105
TEST_TIME 64 10135
TEST_TIME 65 9994
TEST_TIME 66 9898
TEST_TIME 67 9890
TEST_TIME 68 9827
TEST_TIME 69 9743
TEST_TIME 70 9666
TEST_TIME 71 9573
TEST_TIME 72 9680
TEST_TIME 73 9644
TEST_TIME 74 9591
TEST_TIME 75 9612
TEST_TIME 76 9633
TEST_TIME 77 9615
TEST_TIME 78 9631
TEST_TIME 79 9608
TEST_TIME 80 9724
TEST_TIME 81 9699
TEST_TIME 82 9769
TEST_TIME 83 9851
TEST_TIME 84 9787
TEST_TIME 85 9696
TEST_TIME 86 9638
TEST_TIME 87 9658
TEST_TIME 88 9577
TEST_TIME 89 9518
TEST_TIME 90 9506
TEST_TIME 91 9538
TEST_TIME 92 9540
TEST_TIME 93 9514
TEST_TIME 94 9518
TEST_TIME 95 9535
TEST_TIME 96 9528
TEST_TIME 97 9547
TEST_TIME 98 9556
TEST_TIME 99 9594
TEST_TIME 100 9609
TEST_TIME 101 9597
TEST_TIME 102 9615
TEST_TIME 103 9639
TEST_TIME 104 9627
TEST_TIME 105 9600
TEST_TIME 106 9568
TEST_TIME 107 9575
TEST_TIME 108 9520
TEST_TIME 109 9472
TEST_TIME 110 9449
TEST_TIME 111 9419
TEST_TIME 112 9400
TEST_TIME 113 9435
TEST_TIME 114 9417
TEST_TIME 115 9429
TEST_TIME 116 9458
TEST_TIME 117 9481
TEST_TIME 118 9539
TEST_TIME 119 9524
TEST_TIME 120 9448

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-11-2021 04:37:59 to 24-11-2021 04:38:02.

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 1568966 1568966 0 0.00 0 15677 7 0 0
doc_list_biud 1568965 1568965 0 0.00 0 50554 32 0 0
sp_add_invoice_to_stock 5203 5076 127 2.44 39 182580 17591 0 127
sp_cancel_adding_invoice 5830 5830 0 0.00 6 63837 781 0 0
sp_cancel_client_order 1780 1780 0 0.00 5 8081 281 0 0
sp_cancel_customer_reserve 573962 573962 0 0.00 1 50555 70 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 5425 86 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 3 3912 90 0 0
sp_cancel_supplier_invoice 6475 6460 15 0.23 2 19208 1003 0 15
sp_cancel_supplier_order 400 400 0 0.00 6 1062649 126545 0 0
sp_cancel_write_off 10142 10142 0 0.00 1 10413 118 0 0
sp_client_order 20896 20896 0 0.00 4 39682 433 0 0
sp_customer_reserve 471351 471351 0 0.00 0 88667 173 0 0
sp_fill_shopping_cart 31997 31997 0 0.00 1 15603 37 0 0
sp_fill_shopping_cart_clo_res 471329 471329 0 0.00 0 16559 16 0 0
sp_get_clo_for_invoice 5053 5053 0 0.00 0 26105 376 0 0
sp_kill_cost_storno 17083 17083 0 0.00 0 6814 61 0 0
sp_kill_qstorno_ret_qs2qd 1164926 1164926 0 0.00 0 50551 38 0 0
sp_kill_qty_storno 613780 613780 0 0.00 0 50554 81 0 0
sp_lock_selected_doc 21042 21042 0 0.00 0 9792 5 0 0
sp_make_cost_storno 28324 28324 0 0.00 0 15643 59 0 0
sp_multiply_rows_for_pdistr 28324 28324 0 0.00 0 9064 34 0 0
sp_multiply_rows_for_qdistr 20896 20896 0 0.00 0 21134 222 0 0
sp_pay_from_customer 7328 7328 0 0.00 4 14461 112 0 0
sp_pay_to_supplier 5790 5790 0 0.00 4 14368 133 0 0
sp_payment_common 13118 13118 0 0.00 1 12971 27 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 8074 252 0 0
sp_qd_handle_on_invoice_upd_sts 10883 10883 0 0.00 0 15490 325 0 0
sp_qd_handle_on_reserve_upd_sts 10153 10153 0 0.00 0 17486 57 0 0
sp_reserve_write_off 10153 10153 0 0.00 7 18791 192 0 0
sp_supplier_invoice 5597 5597 0 0.00 7 77671 3202 0 0
sp_supplier_order 5507 5507 0 0.00 5 36416 1304 0 0
srv_aggregate_perf_data 107 107 0 0.00 2716 239185 17625 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1607 135612 43792 0 0
srv_find_qd_qs_mism 1084266 1084266 0 0.00 0 12280 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 50 108061 3751 0 0
srv_make_money_saldo 704 704 0 0.00 1 20512 609 0 0
srv_recalc_idx_stat 5 5 0 0.00 119906 287464 204046 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5076 5076 0 0.00 0 8732 163 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 0 8278 143 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 8043 19 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 4479 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 1027 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 511 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 6727 29 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 17777 1659 0 0
t$perf-norm:sp_cancel_write_off 1062 1062 0 0.00 0 18224 23 0 0
t$perf-norm:sp_client_order 20896 20896 0 0.00 0 16886 15 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 3498 10 0 0
t$perf-norm:sp_pay_from_customer 7328 7328 0 0.00 0 17694 12 0 0
t$perf-norm:sp_pay_to_supplier 5790 5790 0 0.00 0 7762 11 0 0
t$perf-norm:sp_reserve_write_off 10153 10153 0 0.00 0 12047 10 0 0
t$perf-norm:sp_supplier_invoice 5597 5597 0 0.00 0 18575 17 0 0
t$perf-norm:sp_supplier_order 5507 5507 0 0.00 0 6438 15 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 578 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 3 0 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 3275 19 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 261 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 15 5 0 0
v_add_invoice_to_stock 10358 10104 254 2.45 0 4742 17 0 254
v_all_customers 38614 38614 0 0.00 0 17 1 0 0
v_all_suppliers 30786 30786 0 0.00 0 38 1 0 0
v_all_wares 41792 41792 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 3082 11 0 0
v_cancel_client_order 3560 3560 0 0.00 0 2329 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 1122 6 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2868 6 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 502 4 0 0
v_cancel_supplier_invoice 866 836 30 3.46 0 4679 20 0 30
v_cancel_supplier_order 800 800 0 0.00 0 2954 10 0 0
v_cancel_write_off 2124 2124 0 0.00 0 2424 13 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 265 1 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2997 17 0 0
v_max_id_ord_sup 5594 5594 0 0.00 0 3542 4 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 504 2 0 0
v_max_non_paid_realizn 3694 3694 0 0.00 0 2715 4 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 1588 5 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 2705 12 0 0
v_min_id_ord_sup 5594 5594 0 0.00 0 7674 7 0 0
v_min_non_paid_invoice 5790 5790 0 0.00 0 12967 19 0 0
v_min_non_paid_realizn 7328 7328 0 0.00 0 8814 20 0 0
v_our_firm 10446 10446 0 0.00 0 17 1 0 0
v_reserve_write_off 20306 20306 0 0.00 0 4714 6 0 0
x_lock_depdocs_on_canc_invoice 11872 11872 0 0.00 0 16557 103 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9282 339 0 0
x_make_qstorno_1000_1200 5507 5507 0 0.00 2 36407 1228 0 0
x_make_qstorno_1200_2000 5597 5597 0 0.00 4 77652 3067 0 0
x_make_qstorno_2100_3300 471351 471351 0 0.00 0 88666 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-11-2021 04:38:03 to 24-11-2021 04:38:07.

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
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 39534 8192000000 9720081904 10012270592 791139360 8.806 100 92 184 0 822043040 8343360 22560320
02:10:00 12415 8192000000 9757263728 10060251136 831658000 9.216 100 91 182 0 867673968 14434528 21581440
02:11:23 22247 8192000000 9806073152 10128330752 859366064 9.494 100 76 152 0 909740832 32902336 17472432
02:12:53 12671 8192000000 9878644864 10212720640 925346432 10.149 100 79 158 0 987491312 45741328 16403552
02:14:10 8809 8192000000 9932259920 10282721280 975856480 10.644 100 81 162 0 1048097888 55855984 16385424
02:15:21 2794 8192000000 9991184576 10349862912 1005325488 10.930 100 83 166 0 1110937888 88648784 16963616
02:16:24 3881 8192000000 10009260848 10377322496 1043706816 11.300 100 78 156 0 1124076656 64853600 15516240
02:17:30 1024 8192000000 10041706192 10412204032 1071553808 11.567 100 94 188 0 1158443968 67847904 19042256
02:18:31 2810 8192000000 10021774496 10416852992 1077313008 11.622 100 46 92 0 1140409088 53985712 9110368
02:19:34 7274 8192000000 9995679024 10392600576 1083786816 11.684 100 41 85 1 1116657552 23727568 9143168
02:20:43 10292 8192000000 10007453744 10414829568 1091508016 11.757 100 21 40 1 1111791648 16266960 4016672
02:21:54 28211 8192000000 10019429472 10409889792 1090660432 11.749 100 60 122 0 1118099856 13867280 13572144
02:23:23 3503 8192000000 10037395536 10433085440 1095675392 11.797 100 41 81 2 1150985936 45928896 9381648
02:24:28 5671 8192000000 10018008112 10424532992 1098473952 11.823 100 32 62 1 1125983344 21075520 6433872
02:25:35 5395 8192000000 10022249968 10416930816 1089401808 11.737 99 49 95 1 1123947808 24110992 10435008
02:26:43 31615 8192000000 10021292080 10407755776 1083064352 11.677 99 57 108 0 1111177360 16495808 11617200
02:28:15 8554 8192000000 9975655136 10375782400 1017872640 11.051 95 30 60 1 1052586240 28141616 6571984
02:29:24 9056 8192000000 9960801152 10357665792 1024259584 11.113 98 40 80 1 1050656064 17230912 9165568
02:30:35 12827 8192000000 9940096816 10323120128 965816128 10.546 93 28 56 0 989401840 17546720 6038992
02:31:49 42662 8192000000 9936595440 10338713600 1006785952 10.944 98 20 38 2 1045425152 34784464 3854736
02:33:37 3830 8192000000 9966898288 10351906816 1039286176 11.258 99 44 88 3 1079222832 28945440 10991216
02:34:41 4234 8192000000 9979900592 10369593344 1060858000 11.465 100 47 92 2 1099209392 27564304 10787088
02:35:49 92762 8192000000 10006099008 10407059456 1076181936 11.611 100 27 52 2 1094407392 11423888 6801568
02:38:37 17210 8192000000 10066294128 10469785600 1110390720 11.936 100 57 110 3 1173732528 51923168 11418640
02:40:01 15410 8192000000 10072750000 10469486592 1119932000 12.026 100 50 100 1 1181388176 50882832 10573344
02:41:22 11962 8192000000 10117923312 10520731648 1133025360 12.150 100 52 100 2 1226131024 82584128 10521536
02:42:38 9969 8192000000 10181818128 10583707648 1145040672 12.263 100 70 140 0 1295883024 136281168 14561184
02:43:53 32447 8192000000 10160595072 10603388928 1156778880 12.373 100 37 74 0 1262899184 97455392 8664912
02:45:32 4199 8192000000 10219902720 10628734976 1175353328 12.547 100 59 117 0 1324306032 135896704 13056000
02:46:40 4147 8192000000 10290400224 10690736128 1180768176 12.597 100 67 132 0 1391151920 195766928 14616816
02:47:50 1787 8192000000 10353175520 10757574656 1184371088 12.631 100 86 171 1 1455239312 252265808 18602416
02:48:55 2267 8192000000 10351545408 10773475328 1183062176 12.619 100 69 138 0 1452383360 253550064 15771120
02:50:02 5768 8192000000 10344051712 10771558400 1191225392 12.695 100 74 147 0 1462075136 253467776 17381968
02:51:14 1603 8192000000 10365480736 10784714752 1189071664 12.675 100 87 171 2 1459956016 251978656 18905696
02:52:18 4014 8192000000 10401563984 10814095360 1191296400 12.695 100 72 142 0 1507992016 300500304 16195312
02:53:28 2927 8192000000 10417009120 10811822080 1154712224 12.354 98 80 160 2 1501495168 327887216 18895728
02:54:34 4253 8192000000 10405282816 10853810176 1136267232 12.180 98 60 118 1 1500404880 349627728 14509920
02:55:41 12674 8192000000 10352536400 10809090048 1155685216 12.363 100 39 78 2 1480945888 314941184 10319488
02:56:58 9147 8192000000 10422868016 10837712896 1148245296 12.293 99 50 98 1 1507010688 346487712 12277680
02:58:12 3203 8192000000 10461559808 10866089984 1151756416 12.326 100 74 148 2 1551942000 381328448 18857136
02:59:20 15690 8192000000 10452336496 10906533888 1133510144 12.154 98 42 83 1 1551448560 406939744 10998672
03:00:39 31632 8192000000 10463985952 10911080448 1142428640 12.238 99 34 68 0 1544597824 393553312 8615872
03:02:14 36137 8192000000 10490240000 10966589440 1133514512 12.154 100 24 48 0 1549819600 409529136 6775952
03:04:15 24647 8192000000 10249965536 10781888512 1103349424 11.869 98 26 52 0 1455674480 345752688 6572368
03:05:44 31015 8192000000 10109971552 10563022848 1110156336 11.934 98 22 44 1 1187158592 72707968 4294288
03:07:20 79393 8192000000 10046544192 10466299904 1089666848 11.739 98 24 46 3 1109976960 15241520 5068592
03:09:40 126542 8192000000 10080946032 10543964160 1109356032 11.926 98 26 52 0 1181611664 66854496 5401136
03:12:49 135612 8192000000 10095071584 10523770880 1081675952 11.663 94 27 56 1 1177666352 90158960 5831440
03:16:06 24415 8192000000 10162728720 10581499904 1131293280 12.134 99 44 87 0 1238325072 96825712 10206080
03:17:31 41459 8192000000 10173104016 10618597376 1135224400 12.171 99 32 63 3 1270563888 128171728 7167760
03:19:14 51206 8192000000 10120320384 10651938816 1127484544 12.098 99 15 30 2 1249178400 118293728 3400128
03:21:07 103504 8192000000 10067532848 10550542336 1143613328 12.250 100 10 20 0 1202830448 56192848 3024272
03:23:56 51587 8192000000 10091806784 10540576768 1134436336 12.163 99 15 29 0 1198119408 60545312 3137760
03:25:55 60277 8192000000 10096056720 10540716032 1151420160 12.323 100 19 38 0 1186463504 31061936 3981408
03:27:58 29208 8192000000 10062975760 10512678912 1134625008 12.165 98 9 18 0 1163041856 27005392 1411456
03:29:28 73641 8192000000 10094714768 10556370944 1154136752 12.348 100 8 16 0 1192629296 37055232 1437312
03:31:42 34364 8192000000 10108362352 10548924416 1158721872 12.391 99 26 52 0 1209136912 44878608 5536432
03:33:25 43657 8192000000 10184718624 10629660672 1159821760 12.402 99 32 64 1 1253980480 86920160 7238560
03:35:09 74246 8192000000 10206908096 10671890432 1172857232 12.524 100 19 38 0 1317620784 140582928 4180624
03:37:34 55728 8192000000 10126885600 10575228928 1096810704 11.807 94 28 54 1 1139259376 36619520 5829152
03:39:30 60217 8192000000 10108535248 10558783488 1132405488 12.144 98 23 46 0 1178182272 41078432 4698352
03:41:35 96660 8192000000 10054761392 10482388992 1062809072 11.483 92 25 50 1 1092950480 24819488 5321920
03:44:28 20114 8192000000 10084342480 10530426880 1113562688 11.966 98 44 88 1 1161784544 39304944 8916912
03:46:06 28685 8192000000 10060748288 10526453760 1120911344 12.036 99 14 28 1 1142417168 18686272 2819552
03:47:35 54397 8192000000 10104552624 10551398400 1128920528 12.111 100 14 29 0 1196661024 64096704 3643792
03:49:30 54540 8192000000 10056788960 10560831488 1106576256 11.900 98 4 8 0 1164422064 57258272 587536
03:51:25 36049 8192000000 10075937872 10511712256 1096335984 11.803 98 23 46 1 1153514464 52133280 5045200
03:53:08 28343 8192000000 10027192048 10449887232 1082563504 11.672 98 27 54 0 1140589632 52567232 5458896
03:54:38 41859 8192000000 10079958672 10524704768 1113477024 11.965 100 36 72 0 1194778416 73938496 7362896
03:56:25 44380 8192000000 10088734752 10550816768 1105650192 11.891 99 25 50 2 1203966880 92713424 5603264
03:58:15 42649 8192000000 10089609632 10511818752 1099764480 11.835 98 16 32 0 1159817040 56434528 3618032
04:00:04 103901 8192000000 10043889456 10518007808 1095276352 11.793 98 21 42 0 1151666640 51950704 4439584
04:03:21 25397 8192000000 10069389536 10503270400 1109178384 11.925 99 30 58 1 1162559568 46859600 6521584
04:04:47 52571 8192000000 10058970048 10527842304 1120192848 12.029 100 8 16 0 1169108912 47205136 1710928
04:06:40 60241 8192000000 10085102832 10545315840 1115800848 11.987 100 19 38 0 1189381664 69482496 4098320
04:08:44 60270 8192000000 10045415824 10533167104 1118770512 12.015 100 10 20 0 1171447632 50439344 2237776
04:10:45 49326 8192000000 10118810000 10571804672 1142390192 12.238 100 17 34 0 1211092800 65376368 3326240
04:12:36 80158 8192000000 10098910096 10548817920 1112978944 11.961 97 17 34 1 1184426096 67869968 3577184
04:14:57 11718 8192000000 10090041904 10525085696 1128060784 12.103 99 54 109 1 1182024288 43083280 10880224
04:16:12 34064 8192000000 10068283568 10532315136 1134699360 12.166 100 21 42 0 1189654048 50991568 3963120
04:17:52 44105 8192000000 10102389056 10536181760 1145213360 12.265 100 34 66 1 1178483248 26694816 6575072
04:19:37 62408 8192000000 10089952976 10551734272 1140905296 12.224 99 11 22 0 1228081520 84557296 2618928
04:22:19 97204 8192000000 10095992800 10562146304 1144431088 12.257 99 27 52 0 1201210336 52173248 4606000
04:25:03 48922 8192000000 10075220416 10543292416 1123165760 12.057 99 10 20 0 1159901760 34630656 2105344
04:26:52 89724 8192000000 10118357424 10597408768 1106419184 11.899 97 13 27 0 1199176176 89435408 3321584
04:29:38 80185 8192000000 10095132176 10548297728 1125856432 12.082 99 17 34 1 1216129920 86428464 3845024
04:32:26 57662 8192000000 10095981440 10580983808 1126828160 12.091 99 20 40 0 1220732816 88992176 4912480
04:34:28 48925 8192000000 10113786336 10591715328 1129106720 12.113 99 14 28 0 1212610992 80415056 3089216
04:36:17 59557 8192000000 9226307216 9523003392 364842096 4.263 33 11 22 0 390268608 23365920 2060592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-11-2021 04:38:09 to 24-11-2021 04:38:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-11-2021 04:38:25 to 24-11-2021 04:38:27.

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          238596
MON$OLDEST_ACTIVE               238597
MON$OLDEST_SNAPSHOT             238597
MON$NEXT_TRANSACTION            238597
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                       2061600
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             808
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2668 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2668 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2668 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:265: 
:266: mscs-test-161.budzdorov.ru    Wed Nov 24 04:37:19 2021
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:268: 
:269: 
:270: mscs-test-161.budzdorov.ru    Wed Nov 24 04:37:21 2021
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Wed Nov 24 04:37:21 2021
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:276: 
--- end of diff output ---

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

24.11.21 04:38:28. 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-11-24 04:38:30 No severe PSQL-related problems occured
24.11.21 04:38:31. End of report.