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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16128968 kB
MemAvailable 31031088 kB
Buffers 0 kB
Cached 15502180 kB
SwapCached 2560 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-08-02 19:37:41
DTS_END                         2021-08-02 19:37:41.6550
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_FROM_CUSTOMER(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53554
TRN_ID                          239686
ATT_ID                          666
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: 9535.57 19454 1144268
customer order: creation 175.43 353 21051
customer order: refuse 14.64 349 1757
order to supplier: creation 45.55 1255 5466
order to supplier: removal 3.70 95625 444
invoice (draft): creation 46.23 3440 5547
invoice (draft): removal 54.08 740 6490
invoice accept: apply 41.73 18262 5007
invoice accept: cancel 49.13 810 5895
customer reserve: creation 3964.18 174 475701
customer reserve: removal 4851.84 57 582221
realization accept: apply 84.13 189 10095
realization accept: cancel 74.58 108 8950
payment from customer: creation 59.87 128 7184
payment from customer: removal 5.56 80 667
payment to supplier: creation 48.64 134 5837
payment to supplier: removal 3.68 104 442
service: total inventory turnovers 5.28 1085 634
service: total monetary turnovers 6.57 29 788
service: aggregate perf. data 0.73 27144 88
service: refresh index statistics 0.03 239017 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2021 19:38:39 to 02-08-2021 19:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4246
TEST_TIME 2 5026
TEST_TIME 3 5925
TEST_TIME 4 6298
TEST_TIME 5 6262
TEST_TIME 6 5905
TEST_TIME 7 6479
TEST_TIME 8 6526
TEST_TIME 9 6410
TEST_TIME 10 6800
TEST_TIME 11 6855
TEST_TIME 12 7035
TEST_TIME 13 7298
TEST_TIME 14 7657
TEST_TIME 15 8089
TEST_TIME 16 8356
TEST_TIME 17 8267
TEST_TIME 18 8304
TEST_TIME 19 8605
TEST_TIME 20 8609
TEST_TIME 21 9080
TEST_TIME 22 9017
TEST_TIME 23 9286
TEST_TIME 24 9834
TEST_TIME 25 10151
TEST_TIME 26 10404
TEST_TIME 27 10454
TEST_TIME 28 10977
TEST_TIME 29 11036
TEST_TIME 30 10954
TEST_TIME 31 10939
TEST_TIME 32 10940
TEST_TIME 33 10830
TEST_TIME 34 10748
TEST_TIME 35 10702
TEST_TIME 36 10650
TEST_TIME 37 10600
TEST_TIME 38 10552
TEST_TIME 39 10522
TEST_TIME 40 10574
TEST_TIME 41 10492
TEST_TIME 42 10589
TEST_TIME 43 10465
TEST_TIME 44 10271
TEST_TIME 45 10150
TEST_TIME 46 10237
TEST_TIME 47 10123
TEST_TIME 48 10037
TEST_TIME 49 10006
TEST_TIME 50 9914
TEST_TIME 51 9901
TEST_TIME 52 10060
TEST_TIME 53 9935
TEST_TIME 54 9840
TEST_TIME 55 9869
TEST_TIME 56 9759
TEST_TIME 57 9747
TEST_TIME 58 9720
TEST_TIME 59 9627
TEST_TIME 60 9528
TEST_TIME 61 9589
TEST_TIME 62 9532
TEST_TIME 63 9528
TEST_TIME 64 9639
TEST_TIME 65 9629
TEST_TIME 66 9705
TEST_TIME 67 9723
TEST_TIME 68 9742
TEST_TIME 69 9715
TEST_TIME 70 9876
TEST_TIME 71 9809
TEST_TIME 72 9893
TEST_TIME 73 9884
TEST_TIME 74 9945
TEST_TIME 75 9968
TEST_TIME 76 9916
TEST_TIME 77 9826
TEST_TIME 78 9775
TEST_TIME 79 9725
TEST_TIME 80 9714
TEST_TIME 81 9677
TEST_TIME 82 9611
TEST_TIME 83 9583
TEST_TIME 84 9562
TEST_TIME 85 9537
TEST_TIME 86 9516
TEST_TIME 87 9513
TEST_TIME 88 9483
TEST_TIME 89 9508
TEST_TIME 90 9596
TEST_TIME 91 9572
TEST_TIME 92 9578
TEST_TIME 93 9626
TEST_TIME 94 9623
TEST_TIME 95 9667
TEST_TIME 96 9697
TEST_TIME 97 9679
TEST_TIME 98 9700
TEST_TIME 99 9784
TEST_TIME 100 9751
TEST_TIME 101 9727
TEST_TIME 102 9728
TEST_TIME 103 9664
TEST_TIME 104 9668
TEST_TIME 105 9661
TEST_TIME 106 9669
TEST_TIME 107 9640
TEST_TIME 108 9593
TEST_TIME 109 9560
TEST_TIME 110 9521
TEST_TIME 111 9496
TEST_TIME 112 9504
TEST_TIME 113 9479
TEST_TIME 114 9533
TEST_TIME 115 9530
TEST_TIME 116 9560
TEST_TIME 117 9582
TEST_TIME 118 9545
TEST_TIME 119 9548
TEST_TIME 120 9544

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-08-2021 19:38:42 to 02-08-2021 19:38:46.

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 1583856 1583856 0 0.00 0 26659 6 0 0
doc_list_biud 1583853 1583853 0 0.00 0 68005 27 0 0
sp_add_invoice_to_stock 5143 5007 136 2.64 24 156012 18263 0 136
sp_cancel_adding_invoice 5895 5895 0 0.00 5 70731 810 0 0
sp_cancel_client_order 1757 1757 0 0.00 4 10594 349 0 0
sp_cancel_customer_reserve 582221 582221 0 0.00 1 21098 57 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 3068 80 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 5400 104 0 0
sp_cancel_supplier_invoice 6500 6490 10 0.15 2 68065 741 0 10
sp_cancel_supplier_order 444 444 0 0.00 7 1200604 95625 0 0
sp_cancel_write_off 8950 8950 0 0.00 1 14288 108 0 0
sp_client_order 21051 21051 0 0.00 3 22734 353 0 0
sp_customer_reserve 475701 475701 0 0.00 0 81767 174 0 0
sp_fill_shopping_cart 32056 32056 0 0.00 1 17542 39 0 0
sp_fill_shopping_cart_clo_res 475688 475688 0 0.00 0 12252 13 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 34062 305 0 0
sp_kill_cost_storno 15954 15954 0 0.00 0 9525 57 0 0
sp_kill_qstorno_ret_qs2qd 1180326 1180326 0 0.00 0 68003 31 0 0
sp_kill_qty_storno 620845 620845 0 0.00 0 68004 67 0 0
sp_lock_selected_doc 20868 20868 0 0.00 0 8652 4 0 0
sp_make_cost_storno 28110 28110 0 0.00 0 13831 52 0 0
sp_multiply_rows_for_pdistr 28109 28109 0 0.00 0 7873 34 0 0
sp_multiply_rows_for_qdistr 21051 21051 0 0.00 0 9940 158 0 0
sp_pay_from_customer 7184 7184 0 0.00 4 13973 128 0 0
sp_pay_to_supplier 5837 5837 0 0.00 5 8451 134 0 0
sp_payment_common 13021 13021 0 0.00 2 13968 30 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 10577 319 0 0
sp_qd_handle_on_invoice_upd_sts 10889 10889 0 0.00 0 20698 363 0 0
sp_qd_handle_on_reserve_upd_sts 10094 10094 0 0.00 0 19662 62 0 0
sp_reserve_write_off 10095 10095 0 0.00 6 19822 190 0 0
sp_supplier_invoice 5547 5547 0 0.00 7 78533 3440 0 0
sp_supplier_order 5466 5466 0 0.00 5 30734 1256 0 0
srv_aggregate_perf_data 88 88 0 0.00 2943 576173 27144 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1495 182694 49186 0 0
srv_find_qd_qs_mism 1095290 1095290 0 0.00 0 26659 5 0 0
srv_make_invnt_saldo 634 634 0 0.00 45 54695 1085 0 0
srv_make_money_saldo 788 788 0 0.00 1 2796 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 112302 428448 239017 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5007 5007 0 0.00 0 17771 189 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 4304 106 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 4875 17 0 0
t$perf-norm:sp_cancel_customer_reserve 665 665 0 0.00 0 4788 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2133 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 5540 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 1027 11 0 0
t$perf-norm:sp_cancel_supplier_order 444 444 0 0.00 0 22396 1538 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 8361 14 0 0
t$perf-norm:sp_client_order 21051 21051 0 0.00 0 6967 20 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 573 4 0 0
t$perf-norm:sp_pay_from_customer 7184 7184 0 0.00 0 9911 13 0 0
t$perf-norm:sp_pay_to_supplier 5837 5837 0 0.00 0 5868 10 0 0
t$perf-norm:sp_reserve_write_off 10095 10095 0 0.00 0 12931 12 0 0
t$perf-norm:sp_supplier_invoice 5547 5547 0 0.00 0 10488 24 0 0
t$perf-norm:sp_supplier_order 5466 5466 0 0.00 0 9604 22 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 317 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 378 7 0 0
t$perf-norm:srv_make_invnt_saldo 634 634 0 0.00 0 2452 22 0 0
t$perf-norm:srv_make_money_saldo 788 788 0 0.00 0 1854 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 1371 346 0 0
v_add_invoice_to_stock 10256 9984 272 2.65 0 4690 21 0 272
v_all_customers 37880 37880 0 0.00 0 54 1 0 0
v_all_suppliers 30576 30576 0 0.00 0 49 1 0 0
v_all_wares 42102 42102 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 3927 10 0 0
v_cancel_client_order 3514 3514 0 0.00 0 4644 9 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 2941 7 0 0
v_cancel_customer_reserve 1330 1330 0 0.00 0 3000 6 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 3315 11 0 0
v_cancel_supplier_invoice 788 768 20 2.53 0 4400 23 0 20
v_cancel_supplier_order 888 888 0 0.00 0 2199 7 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3583 17 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 2941 2 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 2087 15 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 2098 3 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 2066 3 0 0
v_max_non_paid_realizn 4067 4067 0 0.00 0 5688 6 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 6304 8 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 6779 14 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 11707 8 0 0
v_min_non_paid_invoice 5837 5837 0 0.00 0 5450 20 0 0
v_min_non_paid_realizn 7184 7184 0 0.00 0 5980 17 0 0
v_our_firm 10456 10456 0 0.00 0 17 1 0 0
v_reserve_write_off 20188 20188 0 0.00 0 6999 8 0 0
x_lock_depdocs_on_canc_invoice 12001 12001 0 0.00 0 18131 126 0 0
x_lock_depdocs_on_canc_sup_ord 444 444 0 0.00 0 15179 356 0 0
x_make_qstorno_1000_1200 5466 5466 0 0.00 2 30718 1174 0 0
x_make_qstorno_1200_2000 5547 5547 0 0.00 3 78517 3301 0 0
x_make_qstorno_2100_3300 475701 475701 0 0.00 0 81766 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2021 19:38:47 to 02-08-2021 19:38:51.

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
17:07:45 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:46 36014 8192000000 10374834016 10697539584 1426177920 14.827 100 86 171 0 1459083904 5798656 27107328
17:10:27 16222 8192000000 10452664896 10786492416 1505177264 15.521 100 88 178 1 1541781376 8540032 28064080
17:11:50 18343 8192000000 10532301440 10880565248 1581911472 16.185 100 89 178 1 1619667872 10508624 27247776
17:13:09 14023 8192000000 10599369136 10958667776 1650397664 16.768 100 79 156 1 1684386096 11312944 22675488
17:14:26 6370 8192000000 10639387936 11009548288 1703958368 17.218 100 74 148 0 1736787088 11209264 21619456
17:15:36 6053 8192000000 10701803040 11079143424 1759635040 17.681 100 84 169 0 1794843600 11790832 23417728
17:16:43 3215 8192000000 10756584576 11136770048 1811404416 18.107 100 88 175 0 1849179472 13420576 24354480
17:17:49 2074 8192000000 10785577392 11175972864 1847424256 18.401 100 90 177 1 1886729984 11934448 27371280
17:18:52 1414 8192000000 10820049776 11207675904 1871660736 18.598 100 93 185 3 1910875440 13237360 25977344
17:19:55 5082 8192000000 10802095744 11205816320 1875499408 18.629 100 67 133 1 1903268736 8982096 18787232
17:21:01 5407 8192000000 10802701456 11221458944 1882103920 18.682 100 37 74 2 1901369152 8389440 10875792
17:22:10 8361 8192000000 10796631184 11222810624 1887987792 18.730 100 31 62 7 1903657264 7242464 8427008
17:23:19 13559 8192000000 10816803488 11246190592 1906330016 18.877 100 32 63 3 1923824192 6525872 10968304
17:24:34 16855 8192000000 10830116992 11253145600 1905403568 18.870 100 24 48 0 1918009408 5802864 6802976
17:25:52 5236 8192000000 10812903840 11245543424 1896665488 18.799 99 21 42 2 1908824176 5637184 6521504
17:26:59 6046 8192000000 10821561648 11254337536 1882234832 18.683 98 31 62 0 1895456048 5340544 7880672
17:28:07 58003 8192000000 10822137168 11228364800 1856535888 18.475 98 36 72 2 1880709936 14044576 10129472
17:30:06 7038 8192000000 10739556224 11158171648 1811914544 18.112 99 21 42 0 1823806912 6221024 5671344
17:31:16 6273 8192000000 10670864240 11089293312 1735565344 17.482 97 33 66 0 1755540864 9765520 10210000
17:32:26 46429 8192000000 10700638672 11115360256 1766903920 17.741 97 27 54 0 1780122336 4770336 8448080
17:34:31 4041 8192000000 10751824496 11178524672 1839497280 18.337 100 34 67 1 1861024720 11711696 9815744
17:35:36 6460 8192000000 10790523648 11214004224 1869212624 18.578 100 25 50 1 1882198880 5270544 7715712
17:36:46 10355 8192000000 10789834528 11226595328 1879420272 18.660 100 23 45 2 1891212016 5316720 6475024
17:38:01 38513 8192000000 10838194496 11260284928 1907162736 18.884 100 54 108 2 1930449616 8044400 15242480
17:39:49 2070 8192000000 10876814960 11289522176 1937019408 19.123 100 66 133 2 1968708864 13437984 18251472
17:40:58 11242 8192000000 10882288592 11311722496 1946911632 19.202 100 55 110 3 1976654656 14354912 15388112
17:42:18 16415 8192000000 10884844880 11324248064 1960806160 19.312 100 30 60 0 1982475168 12971424 8697584
17:43:38 12724 8192000000 10925164480 11360108544 1986054544 19.513 100 69 138 1 2024767600 18105712 20607344
17:45:06 9080 8192000000 10954979392 11385860096 2005707040 19.668 100 69 138 3 2047504720 21548528 20249152
17:46:21 13482 8192000000 10966091696 11398569984 2008938832 19.693 100 56 111 2 2052439216 26447344 17053040
17:47:44 14483 8192000000 10961293248 11403157504 2008190112 19.687 100 60 119 2 2058550176 31562992 18797072
17:49:15 1716 8192000000 10978747856 11400880128 2008370192 19.689 100 80 159 1 2059008176 25885904 24752080
17:50:23 7583 8192000000 10955932352 11386359808 1996265680 19.593 100 71 141 1 2048062752 30162320 21634752
17:51:35 9786 8192000000 10975104976 11406553088 2002963424 19.646 100 62 123 2 2058263376 35888992 19410960
17:52:52 3911 8192000000 10983274032 11402559488 2007545488 19.682 100 77 153 0 2064685712 34464608 22675616
17:54:04 3262 8192000000 10943275776 11382398976 1990747168 19.550 100 56 114 3 2040447504 32002192 17698144
17:55:14 1494 8192000000 10989035872 11403120640 2008214272 19.687 100 84 166 4 2069921392 37321120 24386000
17:56:20 6058 8192000000 10940359520 11361132544 1967610096 19.366 100 62 124 0 2023142064 35847248 19684720
17:57:32 2844 8192000000 10980498480 11394969600 1979698608 19.462 99 91 182 4 2051228384 42710000 28819776
17:58:41 8100 8192000000 10968125136 11406974976 1982613152 19.485 99 50 101 1 2041339792 42515456 16211184
17:59:55 8664 8192000000 10986170576 11414458368 1994952624 19.583 100 60 120 0 2065286640 51189888 19144128
18:01:14 19674 8192000000 10917169296 11369603072 1956430224 19.278 99 36 73 1 2011902096 43816128 11655744
18:02:55 9585 8192000000 10888075184 11330580480 1912328400 18.925 98 22 44 0 1951511920 31845664 7337856
18:04:23 49359 8192000000 10898622576 11349245952 1947818608 19.209 100 28 54 1 1986510912 29363072 9329232
18:06:16 32456 8192000000 10892422848 11337846784 1934115744 19.100 99 20 37 2 1950802224 11082592 5603888
18:07:50 114524 8192000000 10890791232 11361677312 1927688976 19.048 98 9 19 1 1943458384 13846224 1923184
18:10:45 76307 8192000000 10897523184 11352657920 1919775536 18.985 97 6 12 1 1933542720 12790832 976352
18:13:07 95196 8192000000 10918142000 11373445120 1946338320 19.197 98 9 18 1 1959767184 11067584 2361280
18:15:43 28317 8192000000 10929755472 11364433920 1961690816 19.319 99 49 98 2 1983616160 7667360 14257984
18:17:19 143855 8192000000 10930142976 11403984896 1972101936 19.402 99 10 19 0 1992558592 17931776 2524880
18:20:53 96198 8192000000 10936811472 11394023424 1981353936 19.475 99 34 68 2 2004400672 13201232 9845504
18:23:30 70196 8192000000 10924976544 11403886592 1991271312 19.554 100 28 56 1 2015255584 15823568 8160704
18:25:43 31683 8192000000 10929318592 11397214208 1983388624 19.492 99 28 56 1 2001998912 10972608 7637680
18:27:43 89595 8192000000 10941693056 11435368448 1997206864 19.601 99 29 58 1 2027852368 21034352 9611152
18:30:20 98883 8192000000 10911330944 11381850112 1937772768 19.129 97 14 28 0 1956044304 14078112 4193424
18:33:29 45695 8192000000 10877350176 11364143104 1946232864 19.196 99 16 32 1 1959745200 8933328 4579008
18:35:26 49579 8192000000 10887953072 11378794496 1966307840 19.356 100 5 8 1 1977317632 9711136 1298656
18:37:30 54265 8192000000 10886124608 11374878720 1965826992 19.352 100 15 30 0 1982736176 13203200 3705984
18:39:25 54598 8192000000 10884820384 11354968064 1844792528 18.380 93 15 30 0 1862659920 14050224 3817168
18:41:24 34280 8192000000 10885235968 11355017216 1925153568 19.028 98 18 37 0 1948267888 17262464 5851856
18:43:02 53778 8192000000 10895604944 11367227392 1927215872 19.045 98 19 38 0 1951318128 18249520 5852736
18:45:16 47765 8192000000 10897255728 11355688960 1927685424 19.048 98 37 75 1 1950865776 12232928 10947424
18:47:06 113665 8192000000 10871421520 11327225856 1819024496 18.170 94 12 24 2 1835251632 13007504 3219632
18:50:01 65455 8192000000 10851175152 11316641792 1812109088 18.113 93 19 38 1 1827011072 9530752 5371232
18:52:12 182693 8192000000 10845157696 11303477248 1774427216 17.804 92 24 48 1 1793674928 12268704 6979008
18:56:17 16009 8192000000 10874821296 11313836032 1914461072 18.942 99 57 114 1 1940610960 9937808 16212080
18:57:33 31995 8192000000 10844400880 11313192960 1887551360 18.726 98 17 31 1 1902083104 10303024 4228720
18:59:06 69228 8192000000 10862756016 11333890048 1903405024 18.854 98 8 16 0 1917710896 11870544 2435328
19:01:26 48298 8192000000 10858781376 11347005440 1944874368 19.186 100 9 17 2 1958388624 10475440 3038816
19:03:16 61103 8192000000 10847886048 11330887680 1929664880 19.064 100 17 34 1 1943403360 8917808 4820672
19:05:25 29062 8192000000 10887563632 11357229056 1955370608 19.269 100 23 46 1 1970166960 8439632 6356720
19:07:00 60596 8192000000 10896776608 11391528960 1970094080 19.386 100 10 20 2 1987603888 14390528 3119280
19:09:04 47589 8192000000 10919797088 11396894720 1985444080 19.508 100 23 46 0 2006034864 14461920 6128864
19:11:00 65338 8192000000 10931428448 11408613376 1991583088 19.556 100 15 30 0 2012838064 17500560 3754416
19:13:05 77208 8192000000 10926370128 11406823424 1959133824 19.299 98 7 15 1 1975065984 14281216 1650944
19:15:24 88188 8192000000 10925345888 11414110208 1957386176 19.285 98 5 10 0 1977040128 18262736 1391216
19:17:53 36782 8192000000 10931032928 11381374976 1940034176 19.147 97 29 56 3 1957116080 9755584 7326320
19:20:23 126102 8192000000 10904111104 11402379264 1961878192 19.321 99 21 41 0 1984745648 17085968 5781488
19:23:41 71051 8192000000 10901326512 11395911680 1949323216 19.221 98 16 30 2 1962507936 8794752 4389968
19:25:52 84189 8192000000 10905326080 11389833216 1957085600 19.283 98 13 26 0 1974454096 13612288 3756208
19:28:26 70542 8192000000 10890040576 11378597888 1953188944 19.252 98 16 32 0 1969994096 11773472 5031680
19:30:46 83928 8192000000 10912201136 11401506816 1986958944 19.520 100 7 14 1 2001697808 12725568 2013296
19:33:19 57653 8192000000 10897101728 11383087104 1941110112 19.156 98 21 42 1 1956286496 9395136 5781248
19:35:18 54605 8192000000 10908690128 11368038400 1923874816 19.018 98 27 54 0 1946462384 15509440 7078128
19:37:14 40983 8192000000 9382722320 9671524352 530074672 6.077 27 7 14 0 541679808 10107872 1497264

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 02-08-2021 19:38:53 to 02-08-2021 19:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2021 19:39:08 to 02-08-2021 19:39:10.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239020
MON$OLDEST_ACTIVE               239951
MON$OLDEST_SNAPSHOT             239951
MON$NEXT_TRANSACTION            239951
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-03 16:41:55.3470
MON$PAGES                       2053792
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:39: 
:40: mscs-test-161.budzdorov.ru    Mon Aug  2 19:37:58 2021
:41:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:42: 
:43: 
:44: mscs-test-161.budzdorov.ru    Mon Aug  2 19:38:00 2021
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
--- end of diff output ---

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

02.08.21 19:39:11. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-08-02 19:39:13 No severe PSQL-related problems occured
02.08.21 19:39:15. End of report.