Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.05.2022 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 16352668 kB
MemAvailable 31371960 kB
Buffers 20 kB
Cached 15258452 kB
SwapCached 2636 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.2
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 2022-05-16 04:37:16
DTS_END                         2022-05-16 04:37:16.6460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37544
TRN_ID                          212982
ATT_ID                          579
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: 8677.78 23398 1041334
customer order: creation 162.81 512 19537
customer order: refuse 14.60 391 1752
order to supplier: creation 42.03 1414 5043
order to supplier: removal 3.43 160433 411
invoice (draft): creation 43.31 3192 5197
invoice (draft): removal 50.68 999 6082
invoice accept: apply 38.80 27538 4656
invoice accept: cancel 45.55 1097 5466
customer reserve: creation 3570.95 266 428514
customer reserve: removal 4429.20 96 531504
realization accept: apply 78.89 293 9467
realization accept: cancel 76.45 168 9174
payment from customer: creation 54.51 182 6541
payment from customer: removal 5.37 116 644
payment to supplier: creation 45.86 193 5503
payment to supplier: removal 3.46 173 415
service: total inventory turnovers 3.72 4308 446
service: total monetary turnovers 7.23 350 867
service: aggregate perf. data 0.93 15782 111
service: refresh index statistics 0.03 250464 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-05-2022 04:38:08 to 16-05-2022 04:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3113
TEST_TIME 2 3240
TEST_TIME 3 4478
TEST_TIME 4 4672
TEST_TIME 5 4423
TEST_TIME 6 4570
TEST_TIME 7 5104
TEST_TIME 8 5463
TEST_TIME 9 5495
TEST_TIME 10 6075
TEST_TIME 11 6121
TEST_TIME 12 6224
TEST_TIME 13 6418
TEST_TIME 14 6650
TEST_TIME 15 6692
TEST_TIME 16 6691
TEST_TIME 17 6812
TEST_TIME 18 7065
TEST_TIME 19 7589
TEST_TIME 20 7692
TEST_TIME 21 7716
TEST_TIME 22 7777
TEST_TIME 23 8217
TEST_TIME 24 8593
TEST_TIME 25 8689
TEST_TIME 26 9210
TEST_TIME 27 9473
TEST_TIME 28 9343
TEST_TIME 29 9869
TEST_TIME 30 10114
TEST_TIME 31 10805
TEST_TIME 32 10829
TEST_TIME 33 11010
TEST_TIME 34 10695
TEST_TIME 35 10563
TEST_TIME 36 10470
TEST_TIME 37 10367
TEST_TIME 38 10152
TEST_TIME 39 10014
TEST_TIME 40 10002
TEST_TIME 41 9878
TEST_TIME 42 9756
TEST_TIME 43 9634
TEST_TIME 44 9636
TEST_TIME 45 9560
TEST_TIME 46 9594
TEST_TIME 47 9448
TEST_TIME 48 9339
TEST_TIME 49 9278
TEST_TIME 50 9162
TEST_TIME 51 9087
TEST_TIME 52 9100
TEST_TIME 53 9063
TEST_TIME 54 9051
TEST_TIME 55 9005
TEST_TIME 56 8927
TEST_TIME 57 8869
TEST_TIME 58 8885
TEST_TIME 59 8875
TEST_TIME 60 8953
TEST_TIME 61 8971
TEST_TIME 62 9012
TEST_TIME 63 9098
TEST_TIME 64 9077
TEST_TIME 65 9060
TEST_TIME 66 9105
TEST_TIME 67 9066
TEST_TIME 68 9042
TEST_TIME 69 9094
TEST_TIME 70 9070
TEST_TIME 71 9085
TEST_TIME 72 9073
TEST_TIME 73 9090
TEST_TIME 74 8994
TEST_TIME 75 8950
TEST_TIME 76 8883
TEST_TIME 77 8875
TEST_TIME 78 8851
TEST_TIME 79 8797
TEST_TIME 80 8747
TEST_TIME 81 8710
TEST_TIME 82 8725
TEST_TIME 83 8690
TEST_TIME 84 8684
TEST_TIME 85 8707
TEST_TIME 86 8742
TEST_TIME 87 8710
TEST_TIME 88 8723
TEST_TIME 89 8716
TEST_TIME 90 8741
TEST_TIME 91 8722
TEST_TIME 92 8785
TEST_TIME 93 8767
TEST_TIME 94 8775
TEST_TIME 95 8817
TEST_TIME 96 8842
TEST_TIME 97 8855
TEST_TIME 98 8848
TEST_TIME 99 8791
TEST_TIME 100 8783
TEST_TIME 101 8766
TEST_TIME 102 8743
TEST_TIME 103 8717
TEST_TIME 104 8689
TEST_TIME 105 8650
TEST_TIME 106 8606
TEST_TIME 107 8598
TEST_TIME 108 8569
TEST_TIME 109 8604
TEST_TIME 110 8629
TEST_TIME 111 8603
TEST_TIME 112 8610
TEST_TIME 113 8639
TEST_TIME 114 8652
TEST_TIME 115 8615
TEST_TIME 116 8610
TEST_TIME 117 8634
TEST_TIME 118 8710
TEST_TIME 119 8716
TEST_TIME 120 8704

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-05-2022 04:38:11 to 16-05-2022 04:38:15.

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 1437169 1437169 0 0.00 0 23345 14 0 0
doc_list_biud 1437158 1437158 0 0.00 0 107863 41 0 0
sp_add_invoice_to_stock 4782 4656 126 2.63 64 226833 27538 0 126
sp_cancel_adding_invoice 5466 5466 0 0.00 5 108927 1098 0 0
sp_cancel_client_order 1752 1752 0 0.00 5 12097 391 0 0
sp_cancel_customer_reserve 531504 531504 0 0.00 1 29062 97 0 0
sp_cancel_pay_from_customer 644 644 0 0.00 3 3513 117 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 4 22348 173 0 0
sp_cancel_supplier_invoice 6088 6082 6 0.09 2 107868 1000 0 6
sp_cancel_supplier_order 411 411 0 0.00 19 1321289 160434 0 0
sp_cancel_write_off 9174 9174 0 0.00 2 11936 168 0 0
sp_client_order 19537 19537 0 0.00 5 32367 512 0 0
sp_customer_reserve 428514 428514 0 0.00 1 51143 266 0 0
sp_fill_shopping_cart 29765 29765 0 0.00 1 25277 82 0 0
sp_fill_shopping_cart_clo_res 428485 428485 0 0.00 0 16520 21 0 0
sp_get_clo_for_invoice 4620 4620 0 0.00 0 28478 378 0 0
sp_kill_cost_storno 15699 15699 0 0.00 0 10025 72 0 0
sp_kill_qstorno_ret_qs2qd 1078675 1078675 0 0.00 0 107862 43 0 0
sp_kill_qty_storno 568474 568474 0 0.00 0 107863 97 0 0
sp_lock_selected_doc 19679 19679 0 0.00 0 9083 7 0 0
sp_make_cost_storno 26131 26131 0 0.00 0 14872 84 0 0
sp_multiply_rows_for_pdistr 26130 26130 0 0.00 0 10083 40 0 0
sp_multiply_rows_for_qdistr 19536 19536 0 0.00 0 15028 215 0 0
sp_pay_from_customer 6541 6541 0 0.00 5 22364 182 0 0
sp_pay_to_supplier 5503 5503 0 0.00 6 18602 194 0 0
sp_payment_common 12043 12043 0 0.00 2 9796 44 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 11613 329 0 0
sp_qd_handle_on_invoice_upd_sts 10085 10085 0 0.00 0 25264 491 0 0
sp_qd_handle_on_reserve_upd_sts 9466 9466 0 0.00 0 21659 66 0 0
sp_reserve_write_off 9467 9467 0 0.00 8 22281 293 0 0
sp_supplier_invoice 5197 5197 0 0.00 10 79480 3192 0 0
sp_supplier_order 5043 5043 0 0.00 7 38912 1415 0 0
srv_aggregate_perf_data 112 111 1 0.89 2621 169212 15782 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 761 170631 41593 0 0
srv_find_qd_qs_mism 996280 996280 0 0.00 0 23340 11 0 0
srv_make_invnt_saldo 446 446 0 0.00 95 103132 4308 0 0
srv_make_money_saldo 867 867 0 0.00 1 10883 351 0 0
srv_recalc_idx_stat 4 4 0 0.00 128159 448163 250465 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 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 4661 4661 0 0.00 0 12915 855 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 2 6059 402 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 1209 24 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 3475 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 644 644 0 0.00 0 1985 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 398 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1949 38 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 86561 6336 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 1119 20 0 0
t$perf-norm:sp_client_order 19538 19538 0 0.00 0 6538 34 0 0
t$perf-norm:sp_customer_reserve 953 953 0 0.00 0 6451 18 0 0
t$perf-norm:sp_pay_from_customer 6541 6541 0 0.00 0 8091 18 0 0
t$perf-norm:sp_pay_to_supplier 5503 5503 0 0.00 0 14574 25 0 0
t$perf-norm:sp_reserve_write_off 9469 9469 0 0.00 0 14756 26 0 0
t$perf-norm:sp_supplier_invoice 5197 5197 0 0.00 0 8661 31 0 0
t$perf-norm:sp_supplier_order 5043 5043 0 0.00 0 7795 32 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 273 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2507 47 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1253 14 0 0
t$perf-norm:srv_make_money_saldo 867 867 0 0.00 0 1725 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 488 139 0 0
v_add_invoice_to_stock 9468 9216 252 2.66 0 5856 29 0 252
v_all_customers 35285 35285 0 0.00 0 181 3 0 0
v_all_suppliers 28568 28568 0 0.00 0 201 4 0 0
v_all_wares 39062 39062 0 0.00 0 50 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 23456 41 0 0
v_cancel_client_order 3504 3504 0 0.00 0 1937 10 0 0
v_cancel_customer_prepayment 1288 1288 0 0.00 0 1446 12 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 3172 15 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 22263 45 0 0
v_cancel_supplier_invoice 790 778 12 1.51 0 1641 22 0 12
v_cancel_supplier_order 822 822 0 0.00 0 4718 21 0 0
v_cancel_write_off 1830 1830 0 0.00 0 6772 31 0 0
v_max_id_clo_ord 5041 5041 0 0.00 0 512 5 0 0
v_max_id_clo_res 953 953 0 0.00 0 8348 21 0 0
v_max_id_ord_sup 5190 5190 0 0.00 0 5046 10 0 0
v_max_non_paid_invoice 1450 1450 0 0.00 0 51 7 0 0
v_max_non_paid_realizn 3610 3610 0 0.00 0 831 6 0 0
v_min_id_clo_ord 5041 5041 0 0.00 0 1573 7 0 0
v_min_id_clo_res 953 953 0 0.00 0 3364 18 0 0
v_min_id_ord_sup 5190 5190 0 0.00 0 8869 17 0 0
v_min_non_paid_invoice 5503 5503 0 0.00 0 8558 18 0 0
v_min_non_paid_realizn 6540 6540 0 0.00 0 9790 18 0 0
v_our_firm 9636 9636 0 0.00 0 59 4 0 0
v_reserve_write_off 18932 18932 0 0.00 0 22138 13 0 0
x_lock_depdocs_on_canc_invoice 11159 11159 0 0.00 0 29071 146 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 16243 407 0 0
x_make_qstorno_1000_1200 5043 5043 0 0.00 2 38904 1259 0 0
x_make_qstorno_1200_2000 5197 5197 0 0.00 5 75847 2954 0 0
x_make_qstorno_2100_3300 428513 428513 0 0.00 0 46556 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-05-2022 04:38:16 to 16-05-2022 04:38:20.

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:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 24203 8192000000 9727896224 10030202880 802748688 8.924 100 84 168 0 835490192 12177088 20564416
02:09:49 24822 8192000000 9772388816 10082033664 844297360 9.343 100 89 177 1 877968736 12466288 21205088
02:11:19 24690 8192000000 9826634784 10148839424 880469216 9.704 100 89 178 1 924854768 24360320 20025232
02:13:03 8188 8192000000 9875777056 10210541568 921837216 10.114 100 86 167 0 988692560 48999904 17855440
02:14:17 3275 8192000000 9938221504 10284187648 966183216 10.549 100 90 174 4 1054288128 69661504 18443408
02:15:23 1575 8192000000 9960938272 10316574720 991134384 10.792 100 88 164 2 1077857840 70758800 15964656
02:16:27 1704 8192000000 9986915376 10351464448 1020791312 11.080 100 94 183 2 1109537456 70777568 17968576
02:17:31 1260 8192000000 10010438256 10378915840 1041551840 11.280 100 92 176 1 1129072464 69736512 17784112
02:18:33 1065 8192000000 10032569392 10405457920 1056138496 11.420 100 95 184 1 1150196880 75205232 18853152
02:19:35 1555 8192000000 10040913632 10423656448 1074904768 11.599 100 93 179 3 1165346240 71676080 18765392
02:20:38 1135 8192000000 10070318048 10449342464 1079157136 11.639 100 94 174 0 1187048944 91039536 16852272
02:21:39 5131 8192000000 10053721808 10442035200 1080091184 11.648 100 69 127 3 1180705824 87850496 12764144
02:22:46 1155 8192000000 10038740064 10426449920 1083812000 11.684 100 87 170 0 1158799360 58110160 16877200
02:23:48 1058 8192000000 10056495392 10444857344 1091325856 11.755 100 94 179 3 1179490416 69350512 18814048
02:24:50 1119 8192000000 10075177872 10466607104 1096506672 11.804 100 96 182 1 1195760528 80688160 18565696
02:25:52 1319 8192000000 10073793344 10464468992 1093700192 11.778 100 97 190 1 1194100912 80919504 19481216
02:26:54 1136 8192000000 10062331680 10453504000 1095968912 11.799 100 95 178 1 1181645184 66771696 18904576
02:27:56 947 8192000000 10031798240 10419793920 1090221072 11.745 100 89 167 1 1151000736 42986784 17792880
02:28:58 1105 8192000000 10051973760 10437648384 1085106864 11.696 100 96 182 3 1170933840 66427552 19399424
02:30:00 1062 8192000000 10056098864 10440773632 1077260464 11.621 99 97 194 0 1173326864 76429232 19637168
02:31:02 717 8192000000 10042996016 10431741952 1074306256 11.593 100 86 165 0 1168378816 76490464 17582096
02:32:03 1083 8192000000 9983541488 10362052608 1040320832 11.268 100 76 146 2 1102797904 47990112 14486960
02:33:05 12908 8192000000 9982192176 10373132288 1038235456 11.248 99 49 97 1 1099908336 51817248 9855632
02:34:18 975 8192000000 9982413536 10363416576 1038512112 11.250 100 93 171 4 1105286896 48455344 18319440
02:35:20 1150 8192000000 10014379824 10394796032 1049937968 11.360 100 95 178 2 1133916528 65200160 18778400
02:36:22 1678 8192000000 10018406496 10400014336 1053962160 11.399 100 78 151 1 1131465456 61981088 15522208
02:37:31 10229 8192000000 10009827008 10394779648 1069190416 11.544 100 69 133 1 1130225808 48272400 12762992
02:38:46 21504 8192000000 10037724336 10419970048 1057930000 11.437 98 64 124 2 1134232496 63470832 12831664
02:40:13 8848 8192000000 10068069408 10470039552 1088679840 11.730 100 60 120 0 1182689328 81771888 12237600
02:41:27 14980 8192000000 10080172832 10488221696 1101562432 11.852 100 48 92 2 1194506048 82740288 10203328
02:42:47 4226 8192000000 10127050416 10513833984 1112663872 11.958 100 76 145 1 1242232656 114275968 15292816
02:43:55 4726 8192000000 10174160336 10573037568 1130338736 12.125 100 72 142 1 1290085328 144097184 15649408
02:45:06 2329 8192000000 10224180672 10630389760 1146700640 12.279 100 82 160 2 1347219056 182355328 18163088
02:46:16 3403 8192000000 10250396208 10652958720 1157510416 12.380 100 80 154 2 1365565568 191402432 16652720
02:47:24 823 8192000000 10276161472 10683752448 1169727584 12.494 100 93 178 0 1388508768 197834592 20946592
02:48:30 996 8192000000 10271503904 10675552256 1160656192 12.409 100 94 171 1 1381622192 201327424 19638576
02:49:34 1579 8192000000 10348754896 10749657088 1182023728 12.609 100 95 188 0 1453101712 249102096 21975888
02:50:38 1173 8192000000 10377971344 10783162368 1192156960 12.703 100 94 181 0 1478127552 266124448 19846144
02:51:43 771 8192000000 10406867552 10820554752 1190204272 12.685 100 91 172 2 1515888912 304846704 20837936
02:52:47 1024 8192000000 10441235968 10844786688 1197131696 12.750 100 97 186 1 1551202720 331118608 22952416
02:53:50 1333 8192000000 10482167264 10899914752 1201783280 12.793 100 93 182 1 1589323280 365462816 22077184
02:54:56 1214 8192000000 10494165360 10919948288 1196143904 12.741 100 90 173 1 1601113408 385319520 19649984
02:56:00 2959 8192000000 10507022944 10971824128 1196777568 12.746 100 74 148 0 1609129168 394546576 17805024
02:57:06 805 8192000000 10500312576 10929074176 1200199744 12.778 100 90 177 1 1605409344 382789296 22420304
02:58:11 1992 8192000000 10538085024 10958082048 1200288112 12.779 100 95 184 2 1638583376 416928848 21366416
02:59:15 927 8192000000 10550156112 10988785664 1201786016 12.793 100 92 182 0 1657817936 432992640 23039280
03:00:17 750 8192000000 10548871696 11039653888 1211337296 12.881 100 89 175 0 1653102208 418726256 23038656
03:01:19 2619 8192000000 10545476816 11008438272 1201452960 12.790 100 66 131 1 1654710672 436605488 16652224
03:02:32 10694 8192000000 10535194864 11034845184 1198123616 12.759 100 44 88 0 1636632656 426350336 12158704
03:03:54 46061 8192000000 10443059408 10925887488 1198476768 12.762 100 36 72 1 1593750032 386035024 9238240
03:05:52 26593 8192000000 10416589248 10946519040 1162066528 12.423 98 28 56 1 1536252672 366773600 7412544
03:07:56 35347 8192000000 10208078336 10823372800 1131294720 12.134 98 13 26 1 1348903984 214297648 3311616
03:12:18 50927 8192000000 10096152448 10553839616 1125793360 12.082 98 24 48 2 1168383936 37777248 4813328
03:14:12 96280 8192000000 10101879856 10556399616 1128211312 12.104 97 11 22 0 1201535072 70983040 2340720
03:16:49 60951 8192000000 10063832048 10535849984 1094009792 11.781 95 14 28 2 1130149984 32285632 3854560
03:18:58 78074 8192000000 10121379312 10560450560 1103676912 11.873 97 27 52 3 1174366144 65183376 5505856
03:21:44 89706 8192000000 10145282656 10585649152 1144589376 12.259 100 21 42 0 1237240416 87445808 5205232
03:24:14 70754 8192000000 10078001328 10517446656 1130295648 12.124 98 43 85 1 1154128096 15196944 8635504
03:26:25 71567 8192000000 10119600336 10589618176 1141193312 12.227 99 25 50 0 1210343936 64308800 4841824
03:28:42 37773 8192000000 10150297056 10614616064 1148741008 12.298 100 40 80 0 1263594464 106484480 8368976
03:30:22 62641 8192000000 10143233760 10599981056 1125884816 12.083 98 14 26 0 1231247504 102388560 2974128
03:32:27 48584 8192000000 10114740720 10579947520 1116986912 11.999 98 17 34 0 1206767536 86043552 3737072
03:34:15 110008 8192000000 10139455776 10599383040 1132953472 12.149 99 16 32 1 1260421200 123736512 3731216
03:37:15 61655 8192000000 10124252624 10585284608 1103472016 11.871 97 18 36 1 1180874800 73325696 4077088
03:39:32 49858 8192000000 10085978704 10566406144 1121936960 12.045 98 11 22 0 1191647856 67644336 2066560
03:41:22 55909 8192000000 10130752544 10561282048 1131625584 12.137 99 25 50 0 1193102576 55345440 6131552
03:43:22 68213 8192000000 10070557728 10501197824 1110140048 11.934 98 21 42 0 1156677552 42256832 4280672
03:45:31 23903 8192000000 10101007568 10529517568 1130958688 12.130 100 45 90 0 1201646960 61281136 9407136
03:47:05 10681 8192000000 10070135648 10489454592 1111381920 11.945 99 46 92 0 1155176880 34536016 9258944
03:48:18 84175 8192000000 10097557664 10564702208 1136594976 12.183 100 21 42 0 1205264912 64530160 4139776
03:50:42 170631 8192000000 10061588992 10504646656 1122620912 12.052 99 16 32 1 1165044384 39139744 3283728
03:55:15 45749 8192000000 10068790144 10533150720 1120285536 12.030 99 19 38 0 1172942672 48542992 4114144
03:57:47 84687 8192000000 10106266240 10587271168 1144723840 12.260 100 15 29 1 1222931184 75040320 3167024
04:00:13 56813 8192000000 10109072416 10596458496 1128025536 12.103 98 20 41 0 1220557792 87561072 4971184
04:02:37 63414 8192000000 10061693136 10537218048 1084250656 11.688 96 13 26 0 1127522000 40367616 2903728
04:04:41 31847 8192000000 10103785552 10555674624 1133868064 12.158 100 25 50 2 1194478656 55762688 4847904
04:06:19 15316 8192000000 10087653424 10514132992 1111067984 11.943 97 50 97 0 1157252048 36547344 9636720
04:07:34 56499 8192000000 10085199808 10519777280 1096616800 11.806 96 24 48 2 1143270400 42105456 4548144
04:09:32 45819 8192000000 10088131648 10526343168 1115002064 11.980 98 36 70 2 1162200320 39735712 7462544
04:11:23 23594 8192000000 10119337040 10554273792 1137428032 12.191 100 42 84 2 1211756528 66126880 8201616
04:12:49 121906 8192000000 10103376240 10563801088 1088763056 11.731 95 34 66 2 1179634272 84395328 6475888
04:16:01 102345 8192000000 10125430096 10596708352 1123045888 12.056 98 38 74 1 1213977776 82937264 7994624
04:19:00 18751 8192000000 10069030752 10517311488 1112032592 11.952 98 29 57 0 1161795632 44249504 5513536
04:20:20 69877 8192000000 10095117808 10548244480 1138597072 12.202 100 24 48 1 1199417872 55944048 4876752
04:22:45 91146 8192000000 10100109472 10588884992 1112524848 11.956 97 12 24 0 1195325840 80118080 2682912
04:25:19 49486 8192000000 10119155760 10577666048 1103567984 11.871 97 22 45 0 1185789856 76638832 5583040
04:27:20 99134 8192000000 10083977792 10520186880 1026730112 11.137 90 23 46 1 1089432864 57791824 4910928
04:30:16 31698 8192000000 10137280464 10597900288 1139578752 12.212 100 40 80 1 1234499408 86550304 8370352
04:31:55 56183 8192000000 10142532352 10583851008 1113927984 11.970 97 22 44 2 1207093248 88251392 4913872
04:33:51 46786 8192000000 10126699408 10576396288 1064229296 11.497 94 32 64 2 1177390624 106382880 6778448
04:35:45 82099 8192000000 10102468048 10602254336 1129627168 12.118 100 26 52 1 1195525856 60589360 5309328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-05-2022 04:38:22 to 16-05-2022 04:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 126 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-05-2022 04:38:38 to 16-05-2022 04:38:40.

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          211684
MON$OLDEST_ACTIVE               213107
MON$OLDEST_SNAPSHOT             213107
MON$NEXT_TRANSACTION            213107
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                       2041120
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:197: 
:198: mscs-test-161.budzdorov.ru    Mon May 16 04:37:26 2022
:199:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:200: 
:201: 
:202: mscs-test-161.budzdorov.ru    Mon May 16 04:37:26 2022
:203:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:204: 
:205: 
:206: mscs-test-161.budzdorov.ru    Mon May 16 04:37:27 2022
:207:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:208: 
--- end of diff output ---

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

16.05.22 04:38:41. 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
2022-05-16 04:38:44 No severe PSQL-related problems occured
16.05.22 04:38:45. End of report.