Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.2021 16:42
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 15882968 kB
MemAvailable 30663492 kB
Buffers 0 kB
Cached 15782736 kB
SwapCached 3844 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-10-09 16:41:46
DTS_END                         2021-10-09 16:41:46.6380
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45778
TRN_ID                          249352
ATT_ID                          644
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: 9392.55 18025 1127106
customer order: creation 182.93 358 21951
customer order: refuse 15.63 364 1876
order to supplier: creation 49.09 1349 5891
order to supplier: removal 3.57 102934 428
invoice (draft): creation 48.40 3517 5808
invoice (draft): removal 53.28 838 6393
invoice accept: apply 42.85 17956 5142
invoice accept: cancel 48.42 867 5810
customer reserve: creation 3897.85 180 467742
customer reserve: removal 4741.92 60 569030
realization accept: apply 88.61 181 10633
realization accept: cancel 81.45 117 9774
payment from customer: creation 62.58 95 7510
payment from customer: removal 6.18 60 741
payment to supplier: creation 53.08 115 6369
payment to supplier: removal 3.51 90 421
service: total inventory turnovers 5.08 1216 610
service: total monetary turnovers 7.24 31 869
service: aggregate perf. data 0.87 19498 104
service: refresh index statistics 0.03 210680 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2021 16:42:36 to 09-10-2021 16:42:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2373
TEST_TIME 2 3460
TEST_TIME 3 3620
TEST_TIME 4 3336
TEST_TIME 5 4627
TEST_TIME 6 4520
TEST_TIME 7 4453
TEST_TIME 8 5084
TEST_TIME 9 5387
TEST_TIME 10 5776
TEST_TIME 11 5676
TEST_TIME 12 5588
TEST_TIME 13 5725
TEST_TIME 14 6264
TEST_TIME 15 6519
TEST_TIME 16 6575
TEST_TIME 17 6780
TEST_TIME 18 6901
TEST_TIME 19 7539
TEST_TIME 20 7873
TEST_TIME 21 8112
TEST_TIME 22 8136
TEST_TIME 23 8723
TEST_TIME 24 8918
TEST_TIME 25 8830
TEST_TIME 26 9250
TEST_TIME 27 9744
TEST_TIME 28 10385
TEST_TIME 29 10681
TEST_TIME 30 10555
TEST_TIME 31 10326
TEST_TIME 32 10303
TEST_TIME 33 10303
TEST_TIME 34 10160
TEST_TIME 35 10170
TEST_TIME 36 9958
TEST_TIME 37 9914
TEST_TIME 38 10033
TEST_TIME 39 9938
TEST_TIME 40 9838
TEST_TIME 41 9818
TEST_TIME 42 9678
TEST_TIME 43 9788
TEST_TIME 44 9962
TEST_TIME 45 9825
TEST_TIME 46 9751
TEST_TIME 47 9591
TEST_TIME 48 9559
TEST_TIME 49 9840
TEST_TIME 50 9763
TEST_TIME 51 9754
TEST_TIME 52 9782
TEST_TIME 53 9864
TEST_TIME 54 9869
TEST_TIME 55 9863
TEST_TIME 56 9826
TEST_TIME 57 9940
TEST_TIME 58 10012
TEST_TIME 59 9956
TEST_TIME 60 10010
TEST_TIME 61 9881
TEST_TIME 62 9799
TEST_TIME 63 9741
TEST_TIME 64 9724
TEST_TIME 65 9648
TEST_TIME 66 9614
TEST_TIME 67 9533
TEST_TIME 68 9475
TEST_TIME 69 9536
TEST_TIME 70 9467
TEST_TIME 71 9563
TEST_TIME 72 9493
TEST_TIME 73 9515
TEST_TIME 74 9579
TEST_TIME 75 9577
TEST_TIME 76 9560
TEST_TIME 77 9541
TEST_TIME 78 9566
TEST_TIME 79 9675
TEST_TIME 80 9630
TEST_TIME 81 9664
TEST_TIME 82 9559
TEST_TIME 83 9499
TEST_TIME 84 9469
TEST_TIME 85 9488
TEST_TIME 86 9411
TEST_TIME 87 9415
TEST_TIME 88 9374
TEST_TIME 89 9400
TEST_TIME 90 9400
TEST_TIME 91 9365
TEST_TIME 92 9381
TEST_TIME 93 9402
TEST_TIME 94 9454
TEST_TIME 95 9439
TEST_TIME 96 9470
TEST_TIME 97 9478
TEST_TIME 98 9503
TEST_TIME 99 9520
TEST_TIME 100 9557
TEST_TIME 101 9546
TEST_TIME 102 9576
TEST_TIME 103 9554
TEST_TIME 104 9498
TEST_TIME 105 9453
TEST_TIME 106 9406
TEST_TIME 107 9413
TEST_TIME 108 9390
TEST_TIME 109 9362
TEST_TIME 110 9345
TEST_TIME 111 9376
TEST_TIME 112 9323
TEST_TIME 113 9337
TEST_TIME 114 9359
TEST_TIME 115 9343
TEST_TIME 116 9397
TEST_TIME 117 9400
TEST_TIME 118 9383
TEST_TIME 119 9405
TEST_TIME 120 9396

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2021 16:42:40 to 09-10-2021 16:42:44.

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 1561746 1561746 0 0.00 0 28729 6 0 0
doc_list_biud 1561746 1561746 0 0.00 0 32298 28 0 0
sp_add_invoice_to_stock 5266 5142 124 2.35 47 158655 17957 0 124
sp_cancel_adding_invoice 5810 5810 0 0.00 5 72223 867 0 0
sp_cancel_client_order 1876 1876 0 0.00 4 19935 365 0 0
sp_cancel_customer_reserve 569030 569030 0 0.00 1 28254 61 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 2 3229 60 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 8972 91 0 0
sp_cancel_supplier_invoice 6399 6393 6 0.09 2 32317 839 0 6
sp_cancel_supplier_order 428 428 0 0.00 7 1087998 102935 0 0
sp_cancel_write_off 9774 9774 0 0.00 1 26303 118 0 0
sp_client_order 21951 21951 0 0.00 3 50768 359 0 0
sp_customer_reserve 467742 467742 0 0.00 0 77371 181 0 0
sp_fill_shopping_cart 33640 33640 0 0.00 1 15036 38 0 0
sp_fill_shopping_cart_clo_res 467735 467735 0 0.00 0 19169 15 0 0
sp_get_clo_for_invoice 5135 5135 0 0.00 0 27594 288 0 0
sp_kill_cost_storno 16746 16746 0 0.00 0 8958 56 0 0
sp_kill_qstorno_ret_qs2qd 1154655 1154655 0 0.00 0 32288 33 0 0
sp_kill_qty_storno 609078 609078 0 0.00 0 32295 72 0 0
sp_lock_selected_doc 21946 21946 0 0.00 0 9131 5 0 0
sp_make_cost_storno 29646 29646 0 0.00 0 27013 52 0 0
sp_multiply_rows_for_pdistr 29646 29646 0 0.00 0 8357 27 0 0
sp_multiply_rows_for_qdistr 21951 21951 0 0.00 0 50761 173 0 0
sp_pay_from_customer 7510 7510 0 0.00 4 9049 95 0 0
sp_pay_to_supplier 6369 6369 0 0.00 4 10793 115 0 0
sp_payment_common 13879 13879 0 0.00 1 10778 27 0 0
sp_qd_handle_on_cancel_clo 1876 1876 0 0.00 0 19923 330 0 0
sp_qd_handle_on_invoice_upd_sts 10944 10944 0 0.00 0 21983 349 0 0
sp_qd_handle_on_reserve_upd_sts 10633 10633 0 0.00 0 20089 65 0 0
sp_reserve_write_off 10633 10633 0 0.00 6 20442 182 0 0
sp_supplier_invoice 5808 5808 0 0.00 7 92591 3517 0 0
sp_supplier_order 5891 5891 0 0.00 4 62248 1350 0 0
srv_aggregate_perf_data 104 104 0 0.00 2852 302670 19498 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1707 157445 42945 0 0
srv_find_qd_qs_mism 1077900 1077900 0 0.00 0 28727 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 39 105608 1217 0 0
srv_make_money_saldo 869 869 0 0.00 1 4773 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 69849 384760 210680 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 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-norm:sp_add_invoice_to_stock 5142 5142 0 0.00 0 10388 164 0 0
t$perf-norm:sp_cancel_adding_invoice 481 481 0 0.00 0 33969 190 0 0
t$perf-norm:sp_cancel_client_order 1876 1876 0 0.00 0 3484 14 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 3343 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 2689 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 2295 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 3381 21 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 18645 1524 0 0
t$perf-norm:sp_cancel_write_off 1101 1101 0 0.00 0 3736 13 0 0
t$perf-norm:sp_client_order 21951 21951 0 0.00 0 26554 19 0 0
t$perf-norm:sp_customer_reserve 1124 1124 0 0.00 0 739 5 0 0
t$perf-norm:sp_pay_from_customer 7510 7510 0 0.00 0 12827 11 0 0
t$perf-norm:sp_pay_to_supplier 6369 6369 0 0.00 0 2871 10 0 0
t$perf-norm:sp_reserve_write_off 10633 10633 0 0.00 0 4235 10 0 0
t$perf-norm:sp_supplier_invoice 5808 5808 0 0.00 0 3777 17 0 0
t$perf-norm:sp_supplier_order 5891 5891 0 0.00 0 4822 15 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 298 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 315 5 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 911 24 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 2740 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 78 24 0 0
v_add_invoice_to_stock 10516 10268 248 2.35 0 13403 18 0 248
v_all_customers 39800 39800 0 0.00 0 20 1 0 0
v_all_suppliers 32808 32808 0 0.00 0 184 1 0 0
v_all_wares 43902 43902 0 0.00 0 47 1 0 0
v_cancel_adding_invoice 962 962 0 0.00 0 370 6 0 0
v_cancel_client_order 3752 3752 0 0.00 0 5371 5 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 2018 5 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 618 4 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1893 8 0 0
v_cancel_supplier_invoice 870 858 12 1.37 0 1790 17 0 12
v_cancel_supplier_order 856 856 0 0.00 0 805 4 0 0
v_cancel_write_off 2202 2202 0 0.00 0 7085 15 0 0
v_max_id_clo_ord 5890 5890 0 0.00 0 611 1 0 0
v_max_id_clo_res 1124 1124 0 0.00 0 8792 21 0 0
v_max_id_ord_sup 5798 5798 0 0.00 0 9741 6 0 0
v_max_non_paid_invoice 1653 1653 0 0.00 0 7694 7 0 0
v_max_non_paid_realizn 4096 4096 0 0.00 0 2966 5 0 0
v_min_id_clo_ord 5890 5890 0 0.00 0 5874 7 0 0
v_min_id_clo_res 1124 1124 0 0.00 0 11006 22 0 0
v_min_id_ord_sup 5798 5798 0 0.00 0 5872 7 0 0
v_min_non_paid_invoice 6369 6369 0 0.00 0 10774 20 0 0
v_min_non_paid_realizn 7510 7510 0 0.00 1 6967 20 0 0
v_our_firm 10930 10930 0 0.00 0 25 1 0 0
v_reserve_write_off 21264 21264 0 0.00 0 7942 5 0 0
x_lock_depdocs_on_canc_invoice 11774 11774 0 0.00 0 16499 126 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 8018 340 0 0
x_make_qstorno_1000_1200 5891 5891 0 0.00 1 62238 1265 0 0
x_make_qstorno_1200_2000 5808 5808 0 0.00 3 92140 3388 0 0
x_make_qstorno_2100_3300 467742 467742 0 0.00 0 77370 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-10-2021 16:42:45 to 09-10-2021 16:42:49.

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
14:11:50 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:12:51 20622 8192000000 9896017504 10206162944 982198496 10.706 100 91 182 0 1011664752 5653696 23812560
14:14:16 22236 8192000000 9942695856 10261168128 1021370464 11.085 100 88 176 0 1052095168 7987264 22737440
14:15:44 19452 8192000000 9981048864 10313220096 1063399616 11.489 100 86 172 0 1094297088 9266544 21630928
14:17:10 10922 8192000000 10034126592 10383933440 1120564512 12.032 100 79 157 0 1149933008 10575264 18793232
14:18:27 6413 8192000000 10074805632 10433388544 1162142112 12.423 100 78 153 0 1192115888 12420896 17552880
14:19:36 5715 8192000000 10120331136 10488156160 1207513856 12.846 100 80 160 2 1238612160 13399152 17699152
14:20:44 4074 8192000000 10158871808 10532487168 1244664832 13.189 100 82 164 0 1276455264 12968928 18821504
14:21:49 2224 8192000000 10184185056 10566615040 1274163904 13.460 100 87 172 3 1307538544 13543072 19831568
14:22:52 18070 8192000000 10194264496 10590674944 1293049616 13.632 100 59 118 2 1316252384 9633248 13569520
14:24:12 6678 8192000000 10200682848 10607931392 1305255344 13.743 100 33 66 0 1324303472 11710368 7337760
14:25:22 10465 8192000000 10221857168 10615189504 1312188992 13.806 100 74 152 2 1334078224 5618432 16270800
14:26:33 6076 8192000000 10201663120 10619801600 1316611120 13.846 100 40 81 1 1332773024 6569920 9591984
14:27:42 9099 8192000000 10198915600 10623651840 1320801680 13.884 100 22 44 0 1332236320 5746432 5688208
14:28:52 9464 8192000000 10214235520 10637754368 1330336112 13.970 100 17 33 1 1340062224 5959936 3766176
14:30:02 12068 8192000000 10216798848 10642497536 1333300144 13.997 100 46 92 0 1349853808 4765856 11787808
14:31:17 3852 8192000000 10231746000 10654269440 1338219808 14.041 100 29 58 1 1355844384 10790304 6834272
14:32:25 4860 8192000000 10176592224 10579681280 1232354976 13.076 94 35 70 2 1247247712 6730704 8162032
14:33:30 71292 8192000000 10147226112 10547740672 1152949904 12.337 90 25 50 1 1164047168 5304160 5793104
14:35:44 7681 8192000000 10153974960 10557149184 1259448000 13.325 100 39 78 1 1274770256 6138928 9183328
14:36:53 7085 8192000000 10148177552 10553106432 1258805280 13.319 100 24 48 1 1270217056 5187360 6224416
14:38:03 17680 8192000000 10175261696 10584551424 1284021440 13.550 100 21 42 0 1294200560 5704400 4474720
14:39:20 20801 8192000000 10177692032 10594189312 1289557408 13.600 100 15 30 0 1299286352 6324288 3404656
14:40:42 4188 8192000000 10189643472 10602881024 1297527680 13.673 100 29 58 1 1309638528 5579376 6531472
14:41:48 18342 8192000000 10224342016 10635608064 1324383392 13.916 100 38 76 2 1342368672 9125168 8860112
14:43:15 8445 8192000000 10258467392 10665332736 1339480560 14.053 100 58 116 2 1367298304 14635904 13181840
14:44:31 14795 8192000000 10258360416 10665046016 1334427888 14.007 99 63 124 1 1359672000 11096560 14147552
14:45:50 62914 8192000000 10254547536 10685390848 1354723760 14.190 100 36 72 2 1374654576 12000704 7930112
14:47:56 16184 8192000000 10281151472 10705231872 1374032176 14.363 100 35 68 2 1398275328 15622000 8621152
14:49:15 3438 8192000000 10314313680 10726985728 1384260048 14.455 100 72 140 2 1424521296 23796832 16464416
14:50:26 4709 8192000000 10331807120 10745200640 1399422080 14.590 100 81 162 3 1439522720 20889072 19211568
14:51:34 7881 8192000000 10328148960 10758578176 1407207376 14.659 100 45 88 0 1441708800 23141264 11360160
14:53:06 5485 8192000000 10334964000 10756083712 1392327376 14.527 99 60 120 1 1432049312 24246656 15475280
14:54:15 7724 8192000000 10345459040 10770653184 1410487520 14.688 100 47 93 2 1450633344 27953232 12192592
14:55:28 6014 8192000000 10348832864 10768531456 1400273872 14.597 99 81 163 1 1450654912 29754528 20626512
14:56:40 4738 8192000000 10364420912 10773946368 1404873440 14.638 100 85 167 0 1460201136 34988432 20339264
14:57:51 1707 8192000000 10356901904 10763796480 1392657744 14.530 100 81 160 0 1456802880 44090144 20054992
14:58:57 1920 8192000000 10356604336 10765647872 1390882272 14.514 100 72 145 2 1458135696 46872624 20380800
15:00:05 2893 8192000000 10345970912 10756767744 1376337616 14.384 100 75 150 1 1450296528 53701552 20257360
15:01:11 6402 8192000000 10342119248 10764718080 1390322000 14.509 100 54 108 1 1445646928 41605104 13719824
15:02:29 2405 8192000000 10340008176 10759622656 1390083904 14.507 100 55 110 0 1445092096 40840800 14167392
15:03:36 7814 8192000000 10347167120 10772033536 1392709472 14.530 100 47 94 0 1447055664 42201216 12144976
15:04:48 30690 8192000000 10322812560 10750869504 1366532144 14.296 99 34 68 2 1419258544 43450640 9275760
15:06:20 19118 8192000000 10313513168 10742575104 1368737280 14.316 100 31 62 1 1419529008 41673840 9117888
15:07:48 14669 8192000000 10303347616 10736312320 1354830752 14.191 100 30 60 0 1411584992 48205840 8548400
15:09:12 46671 8192000000 10264324000 10720120832 1307709056 13.765 98 9 18 1 1358224800 47625760 2889984
15:11:03 43977 8192000000 10253688288 10693492736 1334713072 14.010 99 12 24 0 1348963552 11993152 2257328
15:12:48 49770 8192000000 10254642784 10691268608 1335561424 14.017 98 39 71 2 1351107280 7230128 8315728
15:14:40 22090 8192000000 10283991568 10720555008 1375725216 14.378 100 20 40 0 1387435664 7376512 4333936
15:16:03 157445 8192000000 10292742896 10735042560 1371795376 14.343 100 19 39 0 1391158688 14954928 4408384
15:19:53 73851 8192000000 10288926464 10740281344 1384693216 14.458 100 25 50 0 1398433344 8233200 5506928
15:22:08 32213 8192000000 10286134752 10734137344 1363649312 14.270 98 26 52 0 1381401504 11651328 6100864
15:23:44 36355 8192000000 10293605632 10738786304 1374900672 14.371 99 44 88 1 1404057152 18756336 10400144
15:25:24 39700 8192000000 10289809488 10744696832 1380806304 14.424 100 14 26 1 1399371952 15110944 3454704
15:27:54 48500 8192000000 10315819760 10767110144 1403228976 14.624 100 17 34 0 1423801216 16037456 4534784
15:29:52 53801 8192000000 10298948528 10774261760 1398756032 14.584 100 13 26 0 1417757504 15965712 3035760
15:32:03 88788 8192000000 10307933232 10768461824 1354544000 14.188 96 17 33 1 1376209632 17259408 4406224
15:34:32 85297 8192000000 10290781024 10743910400 1328220448 13.951 95 12 24 0 1340887728 9463552 3203728
15:36:58 15193 8192000000 10292522688 10728935424 1372261216 14.347 100 50 99 0 1394359632 10777008 11321408
15:38:15 85504 8192000000 10279291776 10739806208 1355383984 14.196 98 10 21 1 1370060208 12591904 2084320
15:40:42 15275 8192000000 10288336064 10724790272 1358119104 14.220 99 46 92 1 1378992384 10435488 10437792
15:42:02 136970 8192000000 10272803392 10727366656 1355950416 14.201 98 27 54 0 1370323696 7968848 6404432
15:45:25 37675 8192000000 10270037200 10729553920 1365069424 14.283 99 17 33 2 1376108752 7421904 3617424
15:47:03 49005 8192000000 10285519568 10746703872 1363427328 14.268 98 18 36 0 1379293648 11402016 4464304
15:48:54 79032 8192000000 10275137040 10735087616 1362826000 14.263 99 17 34 1 1380944608 13639584 4479024
15:51:15 47003 8192000000 10269617648 10714636288 1319074288 13.868 96 20 40 0 1333186736 9139456 4972992
15:53:03 38469 8192000000 10266660144 10715815936 1311689824 13.801 95 17 34 2 1323843520 8092416 4061280
15:54:42 59086 8192000000 10262407888 10723926016 1333393488 13.998 98 11 22 2 1350901568 14647040 2861040
15:57:18 36583 8192000000 10268723152 10706817024 1328306912 13.952 98 33 67 0 1342932256 7217936 7407408
15:58:56 28515 8192000000 10261807792 10707820544 1319089312 13.868 98 29 58 0 1344261328 18645376 6526640
16:00:25 80106 8192000000 10294613088 10726002688 1343561712 14.090 99 24 48 0 1362094800 13092848 5440240
16:02:48 115294 8192000000 10270776752 10711977984 1342389600 14.079 99 22 44 0 1359123840 11947824 4786416
16:05:51 30677 8192000000 10259226848 10718691328 1351597936 14.162 99 11 22 1 1367480864 13379968 2502960
16:07:23 25734 8192000000 10265843248 10733961216 1369816672 14.325 100 6 12 0 1384642928 13479088 1347168
16:08:49 71232 8192000000 10273201408 10740670464 1346314624 14.114 98 15 30 0 1363637056 13732272 3590160
16:12:10 28350 8192000000 10307775344 10757316608 1383148816 14.445 99 28 56 1 1399624816 10309568 6166432
16:13:39 59492 8192000000 10288930512 10756784128 1382657360 14.440 99 14 28 0 1394337456 8576384 3103712
16:15:39 40044 8192000000 10299445520 10747170816 1389877888 14.505 100 23 47 0 1403713728 8841344 4994496
16:17:19 89790 8192000000 10288942112 10750562304 1336831280 14.029 96 14 28 0 1354823536 14701344 3290912
16:19:59 59979 8192000000 10271936272 10723913728 1311805616 13.802 95 23 46 0 1328499360 11371120 5322624
16:22:08 69073 8192000000 10280144144 10745081856 1344770160 14.100 98 9 18 1 1359960304 13365184 1824960
16:24:54 126512 8192000000 10290306432 10745143296 1378745296 14.405 100 23 46 0 1395520064 11450256 5324512
16:28:02 41523 8192000000 10265580176 10735308800 1363008336 14.264 99 4 8 0 1372448544 8501296 938912
16:31:15 45936 8192000000 10265100784 10725826560 1351655136 14.162 98 15 30 0 1364032416 8753088 3624192
16:33:16 76720 8192000000 10276572976 10726965248 1361185968 14.248 99 32 64 0 1382971744 14292384 7493392
16:35:38 40429 8192000000 10288098288 10735165440 1354605184 14.189 99 24 48 1 1375736352 15146896 5984272
16:37:21 69821 8192000000 10271179680 10727284736 1334235904 14.005 97 13 26 1 1347124032 9993200 2894928
16:39:35 52041 8192000000 10288841392 10738491392 1346691376 14.118 98 24 48 0 1364451808 12337328 5423104
16:41:28 13516 8192000000 10284827712 10716401664 1342088000 14.076 98 44 88 1 1362449456 11126288 9235168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-10-2021 16:42:50 to 09-10-2021 16:43:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 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 09-10-2021 16:43:06 to 09-10-2021 16:43:08.

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          246138
MON$OLDEST_ACTIVE               249469
MON$OLDEST_SNAPSHOT             249469
MON$NEXT_TRANSACTION            249469
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                       2034592
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.33520 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33520 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33520 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33520 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:48 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:48 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:50 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:50 2021
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
:22: 
:23: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:54 2021
:24:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:25: 
:26: 
:27: mscs-test-161.budzdorov.ru    Sat Oct  9 16:41:54 2021
:28:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:29: 
--- end of diff output ---

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

09.10.21 16:43:09. 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-10-09 16:43:12 No severe PSQL-related problems occured
09.10.21 16:43:13. End of report.