Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2021 16: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 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 16310172 kB
MemAvailable 31036944 kB
Buffers 0 kB
Cached 15392396 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-11 16:37:44
DTS_END                         2021-08-11 16:37:44.6580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52276
TRN_ID                          252011
ATT_ID                          638
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: 9192.15 16237 1103058
customer order: creation 184.35 323 22122
customer order: refuse 15.84 333 1901
order to supplier: creation 47.78 1335 5733
order to supplier: removal 3.46 88105 415
invoice (draft): creation 47.84 3505 5741
invoice (draft): removal 51.26 749 6151
invoice accept: apply 44.42 19053 5330
invoice accept: cancel 46.05 851 5526
customer reserve: creation 3971.55 196 476586
customer reserve: removal 4468.77 52 536253
realization accept: apply 89.20 166 10704
realization accept: cancel 84.62 109 10154
payment from customer: creation 62.03 99 7444
payment from customer: removal 5.99 103 719
payment to supplier: creation 52.57 119 6308
payment to supplier: removal 3.38 65 406
service: total inventory turnovers 5.16 1074 619
service: total monetary turnovers 6.93 29 831
service: aggregate perf. data 0.93 15080 111
service: refresh index statistics 0.03 193393 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2021 16:40:10 to 11-08-2021 16:40:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4354
TEST_TIME 2 4126
TEST_TIME 3 4167
TEST_TIME 4 4734
TEST_TIME 5 4856
TEST_TIME 6 4831
TEST_TIME 7 5139
TEST_TIME 8 5930
TEST_TIME 9 6088
TEST_TIME 10 6212
TEST_TIME 11 6506
TEST_TIME 12 7256
TEST_TIME 13 7366
TEST_TIME 14 7790
TEST_TIME 15 7783
TEST_TIME 16 7718
TEST_TIME 17 7936
TEST_TIME 18 8400
TEST_TIME 19 8224
TEST_TIME 20 8403
TEST_TIME 21 9022
TEST_TIME 22 9125
TEST_TIME 23 8817
TEST_TIME 24 8894
TEST_TIME 25 9163
TEST_TIME 26 9017
TEST_TIME 27 9386
TEST_TIME 28 9490
TEST_TIME 29 10200
TEST_TIME 30 10172
TEST_TIME 31 10178
TEST_TIME 32 10202
TEST_TIME 33 10219
TEST_TIME 34 10110
TEST_TIME 35 9928
TEST_TIME 36 10056
TEST_TIME 37 10047
TEST_TIME 38 9828
TEST_TIME 39 9876
TEST_TIME 40 9771
TEST_TIME 41 9577
TEST_TIME 42 9555
TEST_TIME 43 9513
TEST_TIME 44 9484
TEST_TIME 45 9593
TEST_TIME 46 9590
TEST_TIME 47 9514
TEST_TIME 48 9561
TEST_TIME 49 9459
TEST_TIME 50 9493
TEST_TIME 51 9465
TEST_TIME 52 9412
TEST_TIME 53 9303
TEST_TIME 54 9186
TEST_TIME 55 9096
TEST_TIME 56 9127
TEST_TIME 57 9136
TEST_TIME 58 9166
TEST_TIME 59 9187
TEST_TIME 60 9162
TEST_TIME 61 9277
TEST_TIME 62 9326
TEST_TIME 63 9410
TEST_TIME 64 9439
TEST_TIME 65 9491
TEST_TIME 66 9519
TEST_TIME 67 9540
TEST_TIME 68 9549
TEST_TIME 69 9635
TEST_TIME 70 9574
TEST_TIME 71 9686
TEST_TIME 72 9585
TEST_TIME 73 9553
TEST_TIME 74 9505
TEST_TIME 75 9527
TEST_TIME 76 9483
TEST_TIME 77 9405
TEST_TIME 78 9392
TEST_TIME 79 9403
TEST_TIME 80 9386
TEST_TIME 81 9342
TEST_TIME 82 9314
TEST_TIME 83 9314
TEST_TIME 84 9318
TEST_TIME 85 9314
TEST_TIME 86 9343
TEST_TIME 87 9322
TEST_TIME 88 9301
TEST_TIME 89 9315
TEST_TIME 90 9300
TEST_TIME 91 9400
TEST_TIME 92 9478
TEST_TIME 93 9455
TEST_TIME 94 9449
TEST_TIME 95 9428
TEST_TIME 96 9410
TEST_TIME 97 9394
TEST_TIME 98 9325
TEST_TIME 99 9343
TEST_TIME 100 9302
TEST_TIME 101 9262
TEST_TIME 102 9222
TEST_TIME 103 9240
TEST_TIME 104 9276
TEST_TIME 105 9268
TEST_TIME 106 9228
TEST_TIME 107 9268
TEST_TIME 108 9261
TEST_TIME 109 9225
TEST_TIME 110 9277
TEST_TIME 111 9329
TEST_TIME 112 9341
TEST_TIME 113 9315
TEST_TIME 114 9347
TEST_TIME 115 9318
TEST_TIME 116 9397
TEST_TIME 117 9320
TEST_TIME 118 9270
TEST_TIME 119 9241
TEST_TIME 120 9197

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2021 16:40:14 to 11-08-2021 16:40:17.

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 1544984 1544984 0 0.00 0 25582 7 0 0
doc_list_biud 1544983 1544983 0 0.00 0 32256 24 0 0
sp_add_invoice_to_stock 5471 5330 141 2.57 37 191083 19053 0 141
sp_cancel_adding_invoice 5526 5526 0 0.00 6 58562 852 0 0
sp_cancel_client_order 1901 1901 0 0.00 4 7122 334 0 0
sp_cancel_customer_reserve 536253 536253 0 0.00 1 23160 53 0 0
sp_cancel_pay_from_customer 719 719 0 0.00 2 9633 104 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 2978 66 0 0
sp_cancel_supplier_invoice 6161 6151 10 0.16 2 21297 749 0 10
sp_cancel_supplier_order 415 415 0 0.00 9 824639 88106 0 0
sp_cancel_write_off 10154 10154 0 0.00 1 11627 109 0 0
sp_client_order 22122 22122 0 0.00 4 26271 323 0 0
sp_customer_reserve 476586 476586 0 0.00 0 137667 196 0 0
sp_fill_shopping_cart 33593 33593 0 0.00 1 21133 38 0 0
sp_fill_shopping_cart_clo_res 476577 476577 0 0.00 0 28981 14 0 0
sp_get_clo_for_invoice 5320 5320 0 0.00 0 28728 269 0 0
sp_kill_cost_storno 16805 16805 0 0.00 0 11395 55 0 0
sp_kill_qstorno_ret_qs2qd 1089226 1089226 0 0.00 0 23153 29 0 0
sp_kill_qty_storno 576424 576424 0 0.00 0 32254 64 0 0
sp_lock_selected_doc 22201 22201 0 0.00 0 8466 4 0 0
sp_make_cost_storno 29776 29776 0 0.00 0 25579 47 0 0
sp_multiply_rows_for_pdistr 29776 29776 0 0.00 0 11344 26 0 0
sp_multiply_rows_for_qdistr 22122 22122 0 0.00 0 9745 140 0 0
sp_pay_from_customer 7444 7444 0 0.00 4 10381 100 0 0
sp_pay_to_supplier 6308 6308 0 0.00 5 25588 119 0 0
sp_payment_common 13752 13752 0 0.00 1 11316 27 0 0
sp_qd_handle_on_cancel_clo 1901 1901 0 0.00 0 7115 313 0 0
sp_qd_handle_on_invoice_upd_sts 10846 10846 0 0.00 0 32251 338 0 0
sp_qd_handle_on_reserve_upd_sts 10704 10704 0 0.00 0 17156 54 0 0
sp_reserve_write_off 10704 10704 0 0.00 6 17194 166 0 0
sp_supplier_invoice 5741 5741 0 0.00 6 103689 3505 0 0
sp_supplier_order 5733 5733 0 0.00 5 36119 1335 0 0
srv_aggregate_perf_data 111 111 0 0.00 3036 115516 15081 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2636 158932 42674 0 0
srv_find_qd_qs_mism 1053270 1053270 0 0.00 0 21079 6 0 0
srv_make_invnt_saldo 619 619 0 0.00 35 83250 1075 0 0
srv_make_money_saldo 831 831 0 0.00 1 1230 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 93630 259761 193394 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 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 5331 5331 0 0.00 0 16019 166 0 0
t$perf-norm:sp_cancel_adding_invoice 463 463 0 0.00 0 9120 125 0 0
t$perf-norm:sp_cancel_client_order 1901 1901 0 0.00 0 5517 20 0 0
t$perf-norm:sp_cancel_customer_reserve 718 718 0 0.00 0 6478 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 719 719 0 0.00 0 1831 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 3945 27 0 0
t$perf-norm:sp_cancel_supplier_invoice 457 457 0 0.00 0 2126 17 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 29192 1663 0 0
t$perf-norm:sp_cancel_write_off 1098 1098 0 0.00 0 2947 14 0 0
t$perf-norm:sp_client_order 22122 22122 0 0.00 0 18874 21 0 0
t$perf-norm:sp_customer_reserve 1114 1114 0 0.00 0 2826 9 0 0
t$perf-norm:sp_pay_from_customer 7444 7444 0 0.00 0 6292 13 0 0
t$perf-norm:sp_pay_to_supplier 6308 6308 0 0.00 0 8545 14 0 0
t$perf-norm:sp_reserve_write_off 10704 10704 0 0.00 0 8574 14 0 0
t$perf-norm:sp_supplier_invoice 5741 5741 0 0.00 0 3897 16 0 0
t$perf-norm:sp_supplier_order 5733 5733 0 0.00 0 7447 18 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 400 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 48 1 0 0
t$perf-norm:srv_make_invnt_saldo 619 619 0 0.00 0 876 25 0 0
t$perf-norm:srv_make_money_saldo 831 831 0 0.00 0 7274 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 4 0 0
v_add_invoice_to_stock 10920 10638 282 2.58 0 3993 18 0 282
v_all_customers 40204 40204 0 0.00 0 105 1 0 0
v_all_suppliers 31820 31820 0 0.00 0 27 1 0 0
v_all_wares 44240 44240 0 0.00 0 45 1 0 0
v_cancel_adding_invoice 926 926 0 0.00 0 2854 13 0 0
v_cancel_client_order 3802 3802 0 0.00 0 2919 4 0 0
v_cancel_customer_prepayment 1438 1438 0 0.00 0 3227 6 0 0
v_cancel_customer_reserve 1436 1436 0 0.00 0 897 3 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 2189 6 0 0
v_cancel_supplier_invoice 934 914 20 2.14 0 584 15 0 20
v_cancel_supplier_order 830 830 0 0.00 0 2192 7 0 0
v_cancel_write_off 2196 2196 0 0.00 0 7140 19 0 0
v_max_id_clo_ord 5731 5731 0 0.00 0 2092 2 0 0
v_max_id_clo_res 1114 1114 0 0.00 0 1682 10 0 0
v_max_id_ord_sup 5740 5740 0 0.00 0 4003 5 0 0
v_max_non_paid_invoice 1869 1869 0 0.00 0 1045 3 0 0
v_max_non_paid_realizn 3970 3970 0 0.00 0 1397 3 0 0
v_min_id_clo_ord 5731 5731 0 0.00 0 4023 8 0 0
v_min_id_clo_res 1114 1114 0 0.00 0 6542 19 0 0
v_min_id_ord_sup 5740 5740 0 0.00 0 8138 7 0 0
v_min_non_paid_invoice 6308 6308 0 0.00 0 11308 22 0 0
v_min_non_paid_realizn 7444 7444 0 0.00 0 6465 16 0 0
v_our_firm 10984 10984 0 0.00 0 13 1 0 0
v_reserve_write_off 21408 21408 0 0.00 0 3567 6 0 0
x_lock_depdocs_on_canc_invoice 11220 11220 0 0.00 0 17470 120 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 11757 423 0 0
x_make_qstorno_1000_1200 5733 5733 0 0.00 1 36104 1251 0 0
x_make_qstorno_1200_2000 5741 5741 0 0.00 2 103332 3367 0 0
x_make_qstorno_2100_3300 476586 476586 0 0.00 0 137665 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2021 16:40:18 to 11-08-2021 16:40:22.

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:07:48 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:49 23507 8192000000 10395465296 10718507008 1446593248 15.008 100 93 186 0 1482950848 6160112 30197488
14:10:14 18346 8192000000 10441849296 10772815872 1493314960 15.418 100 88 176 0 1529937424 8481440 28141024
14:11:45 13986 8192000000 10480546416 10818723840 1532879072 15.762 100 87 174 0 1569403552 9535424 26989056
14:13:03 16432 8192000000 10542370112 10894004224 1599946128 16.339 100 81 162 0 1634909760 11063856 23899776
14:14:24 14469 8192000000 10609049056 10978455552 1661767536 16.864 100 70 141 2 1694162736 11397872 20997328
14:15:43 3704 8192000000 10691181920 11066355712 1752939088 17.626 100 78 156 0 1787482752 12452528 22091136
14:16:49 6592 8192000000 10737375440 11123089408 1806742800 18.069 100 75 148 3 1839559536 12781808 20034928
14:17:57 1558 8192000000 10796450928 11180818432 1848111520 18.407 100 90 180 0 1885328032 11942544 25273968
14:19:00 32742 8192000000 10751561728 11187052544 1858904672 18.494 100 41 82 1 1879691360 9162640 11624048
14:20:34 5298 8192000000 10786794464 11212738560 1881875136 18.680 100 27 54 0 1895829504 5707136 8247232
14:21:44 10807 8192000000 10797130064 11225739264 1885472576 18.709 100 19 39 2 1896601568 5595136 5533856
14:22:57 12473 8192000000 10812457776 11245236224 1903742432 18.856 100 27 54 1 1918107616 5700464 8664720
14:24:12 24716 8192000000 10803775616 11250143232 1904831664 18.865 100 26 52 0 1917907840 5151856 7924320
14:25:40 6443 8192000000 10834295936 11270471680 1918040576 18.971 100 29 58 2 1933248848 5977488 9230784
14:26:47 6343 8192000000 10828941440 11262431232 1913868640 18.938 100 24 48 1 1926647184 5884000 6894544
14:27:57 8028 8192000000 10773083968 11202277376 1852606192 18.443 99 22 44 1 1864418064 5442176 6369696
14:29:09 41321 8192000000 10666354512 11090755584 1532244160 15.756 83 17 34 0 1542298480 5069936 4984384
14:30:50 4108 8192000000 10711117872 11122835456 1777917776 17.832 99 43 86 0 1801425632 11233408 12274448
14:31:56 4255 8192000000 10729850624 11133116416 1804243472 18.049 100 32 64 0 1819125216 4922160 9959584
14:33:02 6530 8192000000 10751891120 11173203968 1832888608 18.283 100 26 52 2 1846587648 6353520 7345520
14:34:11 34162 8192000000 10777165536 11184820224 1846960368 18.397 100 58 116 2 1869516672 5566288 16990016
14:35:46 6026 8192000000 10778816464 11206643712 1862265024 18.522 100 27 54 2 1876021888 5646960 8109904
14:36:53 6665 8192000000 10791533920 11221872640 1875961648 18.632 100 19 38 0 1887093088 5950560 5180880
14:38:04 29006 8192000000 10846411360 11266158592 1916013792 18.955 100 34 66 0 1930840288 4971504 9854992
14:39:37 7846 8192000000 10884623440 11312734208 1947686288 19.208 100 54 108 2 1978178544 14314768 16177488
14:40:47 46092 8192000000 10889857888 11327836160 1957250128 19.284 100 44 88 0 1989237280 19618112 12369040
14:42:40 2636 8192000000 10923100512 11333070848 1966440592 19.357 100 68 135 2 2000826112 14760976 19624544
14:43:47 14886 8192000000 10908050048 11340009472 1966085056 19.354 100 40 80 0 1994436576 16662832 11688688
14:45:11 9627 8192000000 10931902704 11360993280 1968150944 19.371 99 58 113 2 2005838944 20843840 16844160
14:46:28 5012 8192000000 10921662544 11352186880 1933215616 19.093 97 74 148 2 1976428784 21773776 21439392
14:47:38 5533 8192000000 10917790688 11342217216 1958238944 19.292 100 69 138 1 2004298064 25191824 20867296
14:48:47 7144 8192000000 10908447520 11338690560 1953533952 19.255 100 55 109 1 1996264192 26220720 16509520
14:49:58 6528 8192000000 10891809040 11307900928 1933186624 19.092 100 68 136 2 1969274976 15892976 20195376
14:51:06 3966 8192000000 10890188272 11315191808 1943350288 19.173 100 67 133 4 1982891552 19330736 20210528
14:52:15 10799 8192000000 10897658432 11317563392 1940903344 19.154 100 73 144 0 1981561616 19583472 21074800
14:53:34 5056 8192000000 10883009616 11315113984 1940469648 19.151 100 48 97 2 1974385920 18650544 15265728
14:54:42 3095 8192000000 10895282896 11311947776 1927257120 19.045 100 72 145 1 1980285904 30309360 22719424
14:55:47 8768 8192000000 10911731232 11340603392 1936676688 19.120 99 67 132 1 1986935984 29128304 21130992
14:57:02 5001 8192000000 10930700208 11349225472 1952536320 19.247 100 78 154 1 2012362480 35455072 24371088
14:58:14 4147 8192000000 10909467344 11319660544 1916268896 18.957 99 88 175 2 1978647568 35835472 26543200
14:59:25 5935 8192000000 10940808144 11364433920 1971938448 19.401 100 71 142 2 2027416816 33354192 22124176
15:00:34 13916 8192000000 10933584832 11376615424 1970385600 19.389 100 32 64 0 2015129360 33511248 11232512
15:02:22 8888 8192000000 10919340096 11358375936 1955549776 19.271 100 34 68 1 1999439248 32793648 11095824
15:03:59 11200 8192000000 10891185424 11328561152 1922336336 19.006 100 33 65 2 1970857584 37208384 11312864
15:05:14 26525 8192000000 10870944608 11321794560 1907311520 18.885 99 18 36 0 1951112512 36758352 7042640
15:06:42 36682 8192000000 10878520688 11325902848 1947110480 19.203 100 22 44 0 1973526496 19954352 6461664
15:08:27 18499 8192000000 10881109360 11340849152 1961002000 19.314 100 54 106 1 1984475200 8175104 15298096
15:09:47 29361 8192000000 10911047024 11363442688 1972997968 19.409 100 20 37 2 1985283120 7665680 4619472
15:11:18 49696 8192000000 10932288944 11371057152 1941165680 19.156 98 37 74 4 1962391984 9967136 11259168
15:13:35 45950 8192000000 10903736144 11376775168 1977126896 19.442 100 11 22 1 1996377216 15779648 3470672
15:15:24 158932 8192000000 10921860800 11400482816 1968395088 19.373 99 26 52 0 1988026736 12047120 7584528
15:20:52 66521 8192000000 10908526976 11387805696 1953203808 19.252 98 20 39 2 1979716976 20519936 5993232
15:23:53 155506 8192000000 10904279696 11376132096 1958425600 19.294 98 13 26 1 1973403280 11148368 3829312
15:27:29 41853 8192000000 10914356480 11396911104 1992541264 19.564 100 11 22 0 2004479904 8914352 3024288
15:29:50 47921 8192000000 10903407744 11386454016 1981895472 19.480 99 14 28 1 1993476592 8337840 3243280
15:31:46 91856 8192000000 10903548768 11395710976 1944160768 19.180 97 16 32 2 1961636736 12685760 4790208
15:34:21 26276 8192000000 10893804544 11370901504 1961240080 19.316 99 30 58 1 1983109792 13074144 8795568
15:35:55 30098 8192000000 10882944928 11338100736 1845583664 18.386 93 18 36 2 1869558080 18356064 5618352
15:37:29 74740 8192000000 10873099232 11335913472 1864430224 18.539 95 15 30 1 1881053024 12991376 3631424
15:39:49 55007 8192000000 10842251776 11320725504 1834877296 18.299 94 21 41 1 1855742048 15134064 5730688
15:41:46 73340 8192000000 10832907760 11303776256 1815054864 18.137 94 16 32 2 1835822944 15881664 4886416
15:44:05 27664 8192000000 10887653504 11334533120 1942301264 19.165 100 44 88 2 1966475632 11758176 12416192
15:45:37 45543 8192000000 10890378432 11369336832 1939247200 19.141 99 26 52 1 1964872912 18170896 7454816
15:47:24 17002 8192000000 10883402544 11326263296 1892315008 18.764 97 44 88 0 1919016944 14628816 12073120
15:48:44 118177 8192000000 10893539232 11352109056 1914686016 18.944 98 44 84 2 1941699600 14576880 12436704
15:51:43 37117 8192000000 10885919024 11354099712 1940874592 19.154 99 14 28 0 1958351728 13620592 3856544
15:53:22 44432 8192000000 10862635280 11325755392 1876383920 18.636 96 15 30 1 1887384192 6870000 4130272
15:55:11 64338 8192000000 10868179808 11345657856 1936478624 19.119 99 11 22 1 1949558976 9621248 3459104
15:57:25 59261 8192000000 10860127360 11338829824 1925647584 19.032 99 9 18 0 1935331504 7264048 2419872
15:59:24 49089 8192000000 10873363776 11359842304 1959525680 19.302 100 12 24 1 1969275296 6537632 3211984
16:02:15 42214 8192000000 10896211472 11368509440 1968234176 19.371 100 27 54 1 1986040304 9022048 8784080
16:03:59 49203 8192000000 10929003392 11376193536 1964885328 19.345 99 24 44 2 1979596048 8230720 6480000
16:05:51 36917 8192000000 10926075232 11401465856 1970067008 19.386 99 30 60 1 1992077376 13856928 8153440
16:07:31 118822 8192000000 10929379296 11403497472 1955021600 19.266 98 9 18 0 1969813152 12784240 2007312
16:11:50 24986 8192000000 10885782704 11350777856 1934141360 19.100 98 24 49 0 1949564512 8877056 6546096
16:13:15 14892 8192000000 10921881568 11372523520 1962706192 19.328 99 52 104 1 1989635488 12620960 14308336
16:14:32 88446 8192000000 10866099968 11340759040 1932029952 19.083 98 23 44 1 1948058240 10042176 5986112
16:17:36 61595 8192000000 10879555968 11362750464 1917012064 18.963 97 6 12 2 1929366016 10840736 1513216
16:19:38 53839 8192000000 10856715456 11336818688 1905345168 18.869 97 16 32 0 1924081072 14404080 4331824
16:22:02 102372 8192000000 10889763552 11356647424 1865922432 18.551 95 16 32 1 1882167904 11474320 4771152
16:24:46 37480 8192000000 10891757664 11358789632 1922090160 19.004 98 27 53 2 1939553568 9236336 8227072
16:26:35 42746 8192000000 10881594256 11345182720 1931530640 19.079 99 40 80 2 1960875696 17342304 12002752
16:28:27 92855 8192000000 10858315024 11332521984 1827930048 18.242 94 14 28 1 1844811312 13239760 3641504
16:31:03 68790 8192000000 10916808240 11359662080 1936744608 19.121 99 27 52 1 1953830624 9462992 7623024
16:33:13 86784 8192000000 10865590528 11352809472 1907044496 18.883 98 14 28 0 1928012992 16576368 4392128
16:35:49 59962 8192000000 10860653344 11336560640 1925249664 19.029 99 18 34 3 1939523632 8952432 5321536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2021 16:40:24 to 11-08-2021 16:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 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:01, from 11-08-2021 16:40:40 to 11-08-2021 16:40:41.

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          250433
MON$OLDEST_ACTIVE               252137
MON$OLDEST_SNAPSHOT             252137
MON$NEXT_TRANSACTION            252137
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                       2030240
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.33488 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33488 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33488 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33488 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 ---
:106: 
:107: mscs-test-161.budzdorov.ru    Wed Aug 11 16:38:16 2021
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
--- end of diff output ---

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

11.08.21 16:40:42. 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-11 16:40:45 No severe PSQL-related problems occured
11.08.21 16:40:46. End of report.