Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.05.2022 04:39
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 16292368 kB
MemAvailable 31355524 kB
Buffers 20 kB
Cached 15391576 kB
SwapCached 2440 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-31 04:37:18
DTS_END                         2022-05-31 04:37:18.5090
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/41796
TRN_ID                          209051
ATT_ID                          648
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: 8580.02 21771 1029602
customer order: creation 156.06 482 18727
customer order: refuse 13.08 394 1570
order to supplier: creation 40.92 1355 4910
order to supplier: removal 3.44 161184 413
invoice (draft): creation 42.16 3140 5059
invoice (draft): removal 48.79 1007 5855
invoice accept: apply 37.82 28414 4538
invoice accept: cancel 44.33 1252 5319
customer reserve: creation 3574.46 268 428935
customer reserve: removal 4342.33 100 521080
realization accept: apply 76.33 303 9159
realization accept: cancel 81.38 171 9766
payment from customer: creation 53.53 179 6423
payment from customer: removal 5.51 126 661
payment to supplier: creation 45.34 200 5441
payment to supplier: removal 3.16 116 379
service: total inventory turnovers 3.26 4769 391
service: total monetary turnovers 7.16 333 859
service: aggregate perf. data 0.94 17015 113
service: refresh index statistics 0.03 214606 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 04:39:32 to 31-05-2022 04:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4804
TEST_TIME 2 6693
TEST_TIME 3 5701
TEST_TIME 4 5771
TEST_TIME 5 5957
TEST_TIME 6 5984
TEST_TIME 7 6040
TEST_TIME 8 6259
TEST_TIME 9 6345
TEST_TIME 10 6337
TEST_TIME 11 6494
TEST_TIME 12 6580
TEST_TIME 13 6813
TEST_TIME 14 7066
TEST_TIME 15 6984
TEST_TIME 16 7047
TEST_TIME 17 7195
TEST_TIME 18 7460
TEST_TIME 19 8001
TEST_TIME 20 8172
TEST_TIME 21 8239
TEST_TIME 22 8481
TEST_TIME 23 8413
TEST_TIME 24 8720
TEST_TIME 25 8823
TEST_TIME 26 9131
TEST_TIME 27 9221
TEST_TIME 28 9378
TEST_TIME 29 10231
TEST_TIME 30 10954
TEST_TIME 31 10980
TEST_TIME 32 11084
TEST_TIME 33 11090
TEST_TIME 34 10771
TEST_TIME 35 10564
TEST_TIME 36 10548
TEST_TIME 37 10535
TEST_TIME 38 10365
TEST_TIME 39 10263
TEST_TIME 40 10060
TEST_TIME 41 9948
TEST_TIME 42 9822
TEST_TIME 43 9847
TEST_TIME 44 9789
TEST_TIME 45 9711
TEST_TIME 46 9694
TEST_TIME 47 9621
TEST_TIME 48 9522
TEST_TIME 49 9428
TEST_TIME 50 9338
TEST_TIME 51 9398
TEST_TIME 52 9427
TEST_TIME 53 9381
TEST_TIME 54 9293
TEST_TIME 55 9201
TEST_TIME 56 9320
TEST_TIME 57 9340
TEST_TIME 58 9470
TEST_TIME 59 9582
TEST_TIME 60 9515
TEST_TIME 61 9543
TEST_TIME 62 9537
TEST_TIME 63 9461
TEST_TIME 64 9370
TEST_TIME 65 9262
TEST_TIME 66 9220
TEST_TIME 67 9181
TEST_TIME 68 9137
TEST_TIME 69 9090
TEST_TIME 70 9004
TEST_TIME 71 9001
TEST_TIME 72 8930
TEST_TIME 73 8953
TEST_TIME 74 8866
TEST_TIME 75 8843
TEST_TIME 76 8925
TEST_TIME 77 8860
TEST_TIME 78 8841
TEST_TIME 79 8819
TEST_TIME 80 8758
TEST_TIME 81 8694
TEST_TIME 82 8663
TEST_TIME 83 8665
TEST_TIME 84 8641
TEST_TIME 85 8600
TEST_TIME 86 8570
TEST_TIME 87 8557
TEST_TIME 88 8609
TEST_TIME 89 8637
TEST_TIME 90 8660
TEST_TIME 91 8710
TEST_TIME 92 8844
TEST_TIME 93 8814
TEST_TIME 94 8844
TEST_TIME 95 8843
TEST_TIME 96 8785
TEST_TIME 97 8743
TEST_TIME 98 8765
TEST_TIME 99 8764
TEST_TIME 100 8716
TEST_TIME 101 8683
TEST_TIME 102 8643
TEST_TIME 103 8671
TEST_TIME 104 8649
TEST_TIME 105 8629
TEST_TIME 106 8636
TEST_TIME 107 8603
TEST_TIME 108 8600
TEST_TIME 109 8594
TEST_TIME 110 8628
TEST_TIME 111 8664
TEST_TIME 112 8757
TEST_TIME 113 8791
TEST_TIME 114 8796
TEST_TIME 115 8755
TEST_TIME 116 8726
TEST_TIME 117 8678
TEST_TIME 118 8670
TEST_TIME 119 8659
TEST_TIME 120 8597

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-05-2022 04:39:35 to 31-05-2022 04:39:39.

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 1425092 1425092 0 0.00 0 47535 13 0 0
doc_list_biud 1425084 1425084 0 0.00 0 78614 42 0 0
sp_add_invoice_to_stock 4627 4538 89 1.92 72 287617 28415 0 89
sp_cancel_adding_invoice 5319 5319 0 0.00 6 78674 1252 0 0
sp_cancel_client_order 1570 1570 0 0.00 6 9760 395 0 0
sp_cancel_customer_reserve 521080 521080 0 0.00 1 32856 100 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 4 12560 126 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 4 2734 117 0 0
sp_cancel_supplier_invoice 5867 5855 12 0.20 3 61731 1007 0 12
sp_cancel_supplier_order 413 413 0 0.00 12 1398675 161184 0 0
sp_cancel_write_off 9766 9766 0 0.00 2 13930 172 0 0
sp_client_order 18727 18727 0 0.00 6 26249 483 0 0
sp_customer_reserve 428935 428935 0 0.00 1 137652 269 0 0
sp_fill_shopping_cart 28681 28681 0 0.00 1 12874 81 0 0
sp_fill_shopping_cart_clo_res 428906 428906 0 0.00 0 18506 23 0 0
sp_get_clo_for_invoice 4502 4502 0 0.00 0 31520 402 0 0
sp_kill_cost_storno 16125 16125 0 0.00 0 32022 72 0 0
sp_kill_qstorno_ret_qs2qd 1058194 1058194 0 0.00 0 61705 44 0 0
sp_kill_qty_storno 557661 557661 0 0.00 0 78613 100 0 0
sp_lock_selected_doc 18998 18998 0 0.00 0 6710 7 0 0
sp_make_cost_storno 25523 25523 0 0.00 0 47522 85 0 0
sp_multiply_rows_for_pdistr 25522 25522 0 0.00 0 18476 42 0 0
sp_multiply_rows_for_qdistr 18727 18727 0 0.00 0 26173 193 0 0
sp_pay_from_customer 6423 6423 0 0.00 5 13454 179 0 0
sp_pay_to_supplier 5441 5441 0 0.00 6 18491 200 0 0
sp_payment_common 11864 11864 0 0.00 2 13325 49 0 0
sp_qd_handle_on_cancel_clo 1570 1570 0 0.00 1 9590 317 0 0
sp_qd_handle_on_invoice_upd_sts 9818 9818 0 0.00 0 78613 529 0 0
sp_qd_handle_on_reserve_upd_sts 9159 9159 0 0.00 0 20058 65 0 0
sp_reserve_write_off 9159 9159 0 0.00 9 47545 304 0 0
sp_supplier_invoice 5059 5059 0 0.00 9 111548 3141 0 0
sp_supplier_order 4910 4910 0 0.00 7 87780 1355 0 0
srv_aggregate_perf_data 113 113 0 0.00 2480 169651 17015 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 756 126861 41571 0 0
srv_find_qd_qs_mism 984939 984939 0 0.00 0 19620 10 0 0
srv_make_invnt_saldo 391 391 0 0.00 61 120804 4769 0 0
srv_make_money_saldo 859 859 0 0.00 1 8856 333 0 0
srv_recalc_idx_stat 4 4 0 0.00 108675 387395 214607 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4541 4541 0 0.00 1 33319 857 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 1 5579 453 0 0
t$perf-norm:sp_cancel_client_order 1570 1570 0 0.00 0 3442 31 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 2420 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1937 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 1992 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 733 19 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 81379 6639 0 0
t$perf-norm:sp_cancel_write_off 923 923 0 0.00 0 6928 28 0 0
t$perf-norm:sp_client_order 18728 18728 0 0.00 0 32749 34 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 2343 16 0 0
t$perf-norm:sp_pay_from_customer 6423 6423 0 0.00 0 6995 19 0 0
t$perf-norm:sp_pay_to_supplier 5441 5441 0 0.00 0 5154 19 0 0
t$perf-norm:sp_reserve_write_off 9159 9159 0 0.00 0 6861 24 0 0
t$perf-norm:sp_supplier_invoice 5059 5059 0 0.00 0 16160 35 0 0
t$perf-norm:sp_supplier_order 4910 4910 0 0.00 0 5005 28 0 0
t$perf-norm:srv_aggregate_perf_data 113 113 0 0.00 0 473 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 144 6 0 0
t$perf-norm:srv_make_invnt_saldo 391 391 0 0.00 0 5103 23 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 4105 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 23 186 78 0 0
v_add_invoice_to_stock 9164 8986 178 1.94 0 6882 30 0 178
v_all_customers 34442 34442 0 0.00 0 224 3 0 0
v_all_suppliers 28156 28156 0 0.00 0 138 4 0 0
v_all_wares 37452 37452 0 0.00 0 112 3 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 1008 17 0 0
v_cancel_client_order 3140 3140 0 0.00 0 3594 16 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1457 11 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 1 3074 13 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2296 17 0 0
v_cancel_supplier_invoice 786 762 24 3.05 0 5142 27 0 24
v_cancel_supplier_order 826 826 0 0.00 0 3623 18 0 0
v_cancel_write_off 1846 1846 0 0.00 0 5443 33 0 0
v_max_id_clo_ord 4905 4905 0 0.00 0 7521 6 0 0
v_max_id_clo_res 929 929 0 0.00 0 3986 16 0 0
v_max_id_ord_sup 5047 5047 0 0.00 0 3782 8 0 0
v_max_non_paid_invoice 1315 1315 0 0.00 0 1960 8 0 0
v_max_non_paid_realizn 3312 3312 0 0.00 0 13320 13 0 0
v_min_id_clo_ord 4905 4905 0 0.00 0 6489 9 0 0
v_min_id_clo_res 929 929 0 0.00 0 4865 19 0 0
v_min_id_ord_sup 5047 5047 0 0.00 0 3479 7 0 0
v_min_non_paid_invoice 5441 5441 0 0.00 0 11684 21 0 0
v_min_non_paid_realizn 6423 6423 0 0.00 0 10654 22 0 0
v_our_firm 9232 9232 0 0.00 0 53 4 0 0
v_reserve_write_off 18317 18317 0 0.00 0 6884 14 0 0
x_lock_depdocs_on_canc_invoice 10793 10793 0 0.00 0 29266 134 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 8415 336 0 0
x_make_qstorno_1000_1200 4910 4910 0 0.00 3 87736 1196 0 0
x_make_qstorno_1200_2000 5059 5059 0 0.00 5 106942 2916 0 0
x_make_qstorno_2100_3300 428935 428935 0 0.00 0 137647 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 04:39:40 to 31-05-2022 04:39:44.

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:22 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 34756 8192000000 9721370096 10019131392 798536400 8.881 100 86 169 2 827625760 7930192 21159168
02:10:00 18803 8192000000 9766124976 10074664960 841531904 9.315 100 87 174 0 874259792 12239392 20488496
02:11:26 17687 8192000000 9801128672 10120777728 868131056 9.581 100 81 162 0 906121216 19568864 18421296
02:12:52 10149 8192000000 9864445696 10199891968 918187648 10.078 100 81 162 0 978259952 42638576 17433728
02:14:06 6226 8192000000 9943238224 10290995200 979242512 10.677 100 86 173 0 1059592192 62600496 17749184
02:15:16 1925 8192000000 9963480656 10319745024 1008423536 10.960 100 93 181 0 1086804320 60600624 17780160
02:16:21 1356 8192000000 10020409472 10385518592 1030153184 11.170 100 96 184 0 1132116624 83785424 18178016
02:17:25 1319 8192000000 10021298544 10393702400 1055398880 11.412 100 90 168 0 1142582528 70068640 17115008
02:18:28 1197 8192000000 10031838544 10409799680 1067229824 11.526 100 95 183 1 1158220992 72976976 18014192
02:19:30 2216 8192000000 10010480416 10389381120 1072480848 11.576 100 86 156 0 1131476752 42413040 16582864
02:20:35 1215 8192000000 10040585728 10425339904 1076962912 11.619 100 96 182 1 1163316144 66744048 19609184
02:21:37 1168 8192000000 10063329952 10451144704 1081183232 11.659 100 96 179 0 1185217040 86150736 17883072
02:22:40 1008 8192000000 10058526352 10445099008 1086274640 11.707 100 96 185 0 1178148320 73196368 18677312
02:23:42 1359 8192000000 10035763024 10424246272 1091176096 11.754 100 95 188 0 1153955536 44928048 17851392
02:24:44 1200 8192000000 10070368800 10456662016 1098396064 11.822 100 97 192 0 1191240240 73646544 19197632
02:25:47 1014 8192000000 10047417760 10435489792 1092002528 11.762 99 85 169 2 1171992512 63420848 16569136
02:26:48 1145 8192000000 10071837184 10461151232 1095817712 11.798 100 95 180 1 1192290736 78041056 18431968
02:27:50 1181 8192000000 10075437696 10465955840 1100099024 11.839 100 96 181 2 1195749504 76788592 18861888
02:28:53 1186 8192000000 10035391648 10427523072 1086769056 11.712 99 76 149 1 1155225520 54015744 14440720
02:29:55 5389 8192000000 10040532608 10434674688 1081292752 11.660 99 60 117 3 1158871760 66260320 11318688
02:31:02 1083 8192000000 10038699680 10421985280 1072135312 11.572 99 96 183 0 1154981344 63822624 19023408
02:32:05 2947 8192000000 9978359504 10358468608 1027552704 11.145 97 75 150 0 1088621632 45464000 15604928
02:33:09 1041 8192000000 10035556112 10415247360 1062483808 11.480 100 88 170 0 1157697984 77195600 18018576
02:34:11 3923 8192000000 10001144192 10381692928 1034020192 11.207 99 76 143 5 1114774672 67498544 13255936
02:35:17 6260 8192000000 9996127296 10377699328 1042138768 11.285 99 70 135 0 1118931520 63226944 13565808
02:36:28 6409 8192000000 9994822368 10378031104 1041893792 11.283 99 60 111 1 1104628320 51255072 11479456
02:37:40 34798 8192000000 9996705632 10375999488 1055920560 11.417 100 76 126 3 1121991216 54069840 12000816
02:39:17 1150 8192000000 10058471648 10439081984 1080383248 11.651 100 99 193 1 1182004928 81375360 20246320
02:40:33 8333 8192000000 10074943024 10466865152 1094034992 11.781 100 65 128 1 1198683840 91287152 13361696
02:41:47 6737 8192000000 10127019280 10524409856 1108668704 11.920 100 69 134 0 1241992448 120160880 13162864
02:43:02 4450 8192000000 10121468384 10521088000 1120796032 12.035 100 74 140 0 1239697040 104646864 14254144
02:44:11 4427 8192000000 10152149600 10547396608 1132858944 12.148 100 68 120 3 1267442928 120389376 14194608
02:45:19 750 8192000000 10184906352 10583769088 1146183088 12.274 100 96 184 0 1296276032 130568944 19524000
02:46:23 3070 8192000000 10223105040 10621329408 1157669504 12.381 100 84 160 0 1341716080 166518368 17528208
02:47:31 1716 8192000000 10278100864 10687639552 1178075632 12.572 100 93 175 1 1389287936 192020528 19191776
02:48:36 1460 8192000000 10300369552 10701283328 1189230128 12.676 100 84 163 1 1411771904 204014752 18527024
02:49:41 1750 8192000000 10336392320 10761494528 1196988848 12.748 100 82 163 0 1449388608 233376800 19022960
02:50:47 2369 8192000000 10351776320 10768646144 1197447824 12.753 100 87 170 1 1464209152 247915312 18846016
02:51:53 1005 8192000000 10407975744 10815602688 1198251024 12.760 100 96 179 0 1517639280 297097456 22290800
02:52:56 1205 8192000000 10444663376 10852737024 1192035248 12.702 100 87 172 2 1550634800 338666080 19933472
02:54:01 1040 8192000000 10488781488 10901901312 1195805584 12.737 100 98 191 0 1593399184 375100976 22492624
02:55:06 926 8192000000 10478139168 10919055360 1190487696 12.688 100 95 190 2 1583235120 370551968 22195456
02:56:10 1086 8192000000 10465226048 10908323840 1206485888 12.837 100 94 185 1 1571096544 342775584 21835072
02:57:13 3660 8192000000 10474994288 10914955264 1181284192 12.602 99 65 127 0 1576817248 379843168 15689888
02:58:20 2305 8192000000 10471790976 10910928896 1192769072 12.709 100 61 118 2 1578026880 370723504 14534304
02:59:27 3835 8192000000 10481573008 10905853952 1197596400 12.754 100 70 130 1 1587621792 373616144 16409248
03:00:33 5266 8192000000 10544497184 10981556224 1203810096 12.812 100 48 96 0 1648561536 432397968 12353472
03:01:56 17243 8192000000 10516233680 11002191872 1182602000 12.614 100 40 78 1 1638001824 444830192 10569632
03:03:18 18469 8192000000 10482965728 10962309120 1181395152 12.603 100 36 70 0 1589174592 398554256 9225184
03:04:37 19018 8192000000 10476868976 10959958016 1161756912 12.420 99 28 56 0 1592967296 423616352 7594032
03:05:56 26189 8192000000 10336196432 10885124096 1155290160 12.359 100 27 54 0 1515465200 353032288 7142752
03:07:24 75014 8192000000 10144886640 10590134272 1103039664 11.866 95 21 40 2 1192241040 85118768 4082608
03:09:44 3018 8192000000 10165176912 10599436288 1157228560 12.377 100 59 114 0 1261314688 91134384 12951744
03:10:48 113605 8192000000 10133706544 10576715776 1132994544 12.150 99 33 65 0 1227251328 85579952 8676832
03:14:17 84321 8192000000 10103649776 10557718528 1123506752 12.060 98 12 24 1 1190803280 64505104 2791424
03:16:56 106477 8192000000 10132847744 10590887936 1081930560 11.666 94 20 40 0 1167945568 81830672 4184336
03:19:43 70696 8192000000 10140452384 10632798208 1125472912 12.079 99 20 41 0 1245183360 115080048 4630400
03:21:54 126498 8192000000 10093956208 10593234944 1110326736 11.936 98 13 27 1 1195280304 82116528 2837040
03:25:01 89767 8192000000 10112940672 10578042880 1100629408 11.844 96 16 32 1 1169378416 65484208 3264800
03:27:55 82699 8192000000 10118344528 10575343616 1141630048 12.231 100 21 41 2 1206746336 61307328 3808960
03:30:35 53278 8192000000 10125300576 10562068480 1123996240 12.065 98 20 40 0 1196975920 68571072 4408608
03:32:34 61216 8192000000 10180419248 10688389120 1122142128 12.047 99 34 68 1 1260853008 131353552 7357328
03:34:58 126860 8192000000 10084563680 10589990912 1117128912 12.000 98 13 26 0 1204536352 84760688 2646752
03:38:06 40353 8192000000 10108128592 10581426176 1133162736 12.151 100 39 78 0 1210375200 69529712 7682752
03:39:52 57697 8192000000 10117303648 10572111872 1117996576 12.008 97 33 66 0 1154086160 29663072 6426512
03:41:56 95387 8192000000 10104080080 10529558528 1134646992 12.165 99 46 92 0 1190334544 46805088 8882464
03:44:32 66805 8192000000 10081932800 10553331712 1137246496 12.190 99 25 50 0 1215066448 72325888 5494064
03:46:39 63328 8192000000 10097484176 10546528256 1141045216 12.225 99 10 20 0 1176618992 32719600 2854176
03:48:43 71303 8192000000 10091924592 10540224512 1114719520 11.977 97 14 28 1 1176780960 59242400 2819040
03:51:05 50089 8192000000 10111701216 10560708608 1142249744 12.237 99 43 84 0 1205591552 54051072 9290736
03:53:01 72740 8192000000 10065151872 10545504256 1124539584 12.070 98 15 28 1 1157061680 29100544 3421552
03:55:20 62397 8192000000 10073772368 10528985088 1138451520 12.201 99 27 55 1 1176723552 32959968 5312064
03:57:26 61332 8192000000 10041811120 10512052224 1120199360 12.029 99 13 27 0 1143294400 20168672 2926368
03:59:40 42836 8192000000 10076791952 10548006912 1140943328 12.224 100 14 28 0 1191219200 47356992 2918880
04:01:27 28712 8192000000 10120227296 10575806464 1143826432 12.252 99 35 70 1 1200754352 49871040 7056880
04:02:57 21105 8192000000 10129096800 10566426624 1127799808 12.101 99 40 78 1 1214187280 78302368 8085104
04:04:18 32497 8192000000 10209051504 10651226112 1147343184 12.285 100 47 93 2 1289008256 131251824 10413248
04:05:52 40016 8192000000 10167496272 10657767424 1151743072 12.326 100 27 54 1 1299934544 142280016 5911456
04:07:35 36031 8192000000 10200282768 10636124160 1151915312 12.327 99 38 74 0 1274437696 114542832 7979552
04:09:11 84178 8192000000 10109198608 10546483200 1078047376 11.629 94 26 50 0 1125123920 42164128 4912416
04:11:39 6120 8192000000 10120959200 10540470272 1139503488 12.211 100 68 125 4 1202285456 49887328 12894640
04:12:47 16865 8192000000 10123737840 10557562880 1116204224 11.991 97 52 103 2 1202666752 76381648 10080880
04:14:05 69606 8192000000 10122239056 10595262464 1123682240 12.062 98 35 70 1 1204903920 74325088 6896592
04:16:18 76546 8192000000 10061331792 10540347392 1109097376 11.924 98 10 20 0 1164540640 53261728 2181536
04:19:27 56681 8192000000 10069005824 10543751168 1081615728 11.663 95 14 28 0 1143464400 58738496 3110176
04:22:08 35377 8192000000 10116037232 10563182592 1133732464 12.157 99 26 51 0 1189097424 48947056 6417904
04:23:50 22922 8192000000 10136647392 10574622720 1120211424 12.029 97 43 86 0 1196952752 67510176 9231152
04:25:14 54381 8192000000 10168968080 10632941568 1130745840 12.128 98 35 70 1 1266919616 128307152 7866624
04:27:09 64822 8192000000 10194946000 10652569600 1139553056 12.211 99 29 58 0 1317642048 171270208 6818784
04:29:16 21230 8192000000 10129970288 10626326528 1131917040 12.139 100 37 72 2 1244018064 103403488 8697536
04:30:38 77521 8192000000 10084105584 10559012864 1086956800 11.714 96 22 44 1 1152230640 60601040 4672800
04:32:56 120058 8192000000 10092205120 10530877440 1112291328 11.954 98 39 78 0 1172912800 52763392 7858080
04:35:57 70366 8192000000 10056034096 10528555008 1112395584 11.955 99 15 30 0 1147315760 31413376 3506800

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-05-2022 04:39:46 to 31-05-2022 04:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

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

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          207561
MON$OLDEST_ACTIVE               209165
MON$OLDEST_SNAPSHOT             209165
MON$NEXT_TRANSACTION            209165
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                       2044448
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2770 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2770 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2770 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2770 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 ---
:237: 
:238: mscs-test-161.budzdorov.ru    Tue May 31 04:37:37 2022
:239:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:240: 
:241: 
:242: mscs-test-161.budzdorov.ru    Tue May 31 04:37:42 2022
:243:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:244: 
:245: 
:246: mscs-test-161.budzdorov.ru    Tue May 31 04:37:43 2022
:247:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:248: 
:249: 
:250: mscs-test-161.budzdorov.ru    Tue May 31 04:37:43 2022
:251:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:252: 
--- end of diff output ---

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

31.05.22 04:40:05. 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-31 04:40:08 No severe PSQL-related problems occured
31.05.22 04:40:09. End of report.