Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.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 16198348 kB
MemAvailable 30944208 kB
Buffers 0 kB
Cached 15516260 kB
SwapCached 324 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-30 16:37:47
DTS_END                         2021-08-30 16:37:47.8510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42294
TRN_ID                          248241
ATT_ID                          732
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: 9124.34 18748 1094921
customer order: creation 179.43 374 21532
customer order: refuse 15.73 357 1887
order to supplier: creation 48.66 1240 5839
order to supplier: removal 3.55 109474 426
invoice (draft): creation 48.44 3414 5813
invoice (draft): removal 50.76 899 6091
invoice accept: apply 43.33 18029 5200
invoice accept: cancel 45.82 865 5498
customer reserve: creation 3920.98 182 470518
customer reserve: removal 4460.18 68 535221
realization accept: apply 89.11 188 10693
realization accept: cancel 82.52 122 9902
payment from customer: creation 61.91 124 7429
payment from customer: removal 5.87 78 704
payment to supplier: creation 51.41 127 6169
payment to supplier: removal 3.78 66 453
service: total inventory turnovers 5.28 933 633
service: total monetary turnovers 6.72 37 806
service: aggregate perf. data 0.86 16331 103
service: refresh index statistics 0.03 222045 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-08-2021 16:39:58 to 30-08-2021 16:40:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1539
TEST_TIME 2 2441
TEST_TIME 3 3460
TEST_TIME 4 3702
TEST_TIME 5 3807
TEST_TIME 6 4365
TEST_TIME 7 4375
TEST_TIME 8 4324
TEST_TIME 9 4403
TEST_TIME 10 4787
TEST_TIME 11 5028
TEST_TIME 12 5159
TEST_TIME 13 5404
TEST_TIME 14 5569
TEST_TIME 15 6073
TEST_TIME 16 6633
TEST_TIME 17 6892
TEST_TIME 18 7121
TEST_TIME 19 7538
TEST_TIME 20 7753
TEST_TIME 21 8037
TEST_TIME 22 8055
TEST_TIME 23 8393
TEST_TIME 24 8544
TEST_TIME 25 8884
TEST_TIME 26 8924
TEST_TIME 27 9099
TEST_TIME 28 9414
TEST_TIME 29 10162
TEST_TIME 30 10852
TEST_TIME 31 10732
TEST_TIME 32 10614
TEST_TIME 33 10491
TEST_TIME 34 10404
TEST_TIME 35 10521
TEST_TIME 36 10355
TEST_TIME 37 10215
TEST_TIME 38 10191
TEST_TIME 39 10131
TEST_TIME 40 9975
TEST_TIME 41 9848
TEST_TIME 42 9826
TEST_TIME 43 9865
TEST_TIME 44 9706
TEST_TIME 45 9776
TEST_TIME 46 9722
TEST_TIME 47 9610
TEST_TIME 48 9578
TEST_TIME 49 9537
TEST_TIME 50 9524
TEST_TIME 51 9586
TEST_TIME 52 9508
TEST_TIME 53 9433
TEST_TIME 54 9362
TEST_TIME 55 9231
TEST_TIME 56 9180
TEST_TIME 57 9160
TEST_TIME 58 9058
TEST_TIME 59 9024
TEST_TIME 60 9198
TEST_TIME 61 9182
TEST_TIME 62 9197
TEST_TIME 63 9309
TEST_TIME 64 9271
TEST_TIME 65 9340
TEST_TIME 66 9307
TEST_TIME 67 9379
TEST_TIME 68 9369
TEST_TIME 69 9373
TEST_TIME 70 9361
TEST_TIME 71 9385
TEST_TIME 72 9462
TEST_TIME 73 9436
TEST_TIME 74 9390
TEST_TIME 75 9334
TEST_TIME 76 9375
TEST_TIME 77 9290
TEST_TIME 78 9256
TEST_TIME 79 9272
TEST_TIME 80 9218
TEST_TIME 81 9180
TEST_TIME 82 9172
TEST_TIME 83 9133
TEST_TIME 84 9139
TEST_TIME 85 9166
TEST_TIME 86 9162
TEST_TIME 87 9217
TEST_TIME 88 9212
TEST_TIME 89 9244
TEST_TIME 90 9225
TEST_TIME 91 9257
TEST_TIME 92 9290
TEST_TIME 93 9350
TEST_TIME 94 9338
TEST_TIME 95 9339
TEST_TIME 96 9389
TEST_TIME 97 9317
TEST_TIME 98 9280
TEST_TIME 99 9251
TEST_TIME 100 9242
TEST_TIME 101 9231
TEST_TIME 102 9202
TEST_TIME 103 9165
TEST_TIME 104 9153
TEST_TIME 105 9128
TEST_TIME 106 9127
TEST_TIME 107 9121
TEST_TIME 108 9139
TEST_TIME 109 9166
TEST_TIME 110 9166
TEST_TIME 111 9159
TEST_TIME 112 9170
TEST_TIME 113 9195
TEST_TIME 114 9202
TEST_TIME 115 9208
TEST_TIME 116 9209
TEST_TIME 117 9211
TEST_TIME 118 9209
TEST_TIME 119 9173
TEST_TIME 120 9127

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-08-2021 16:40:02 to 30-08-2021 16:40:05.

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 1531799 1531799 0 0.00 0 22214 7 0 0
doc_list_biud 1531799 1531799 0 0.00 0 65221 30 0 0
sp_add_invoice_to_stock 5315 5200 115 2.16 51 228526 18029 0 115
sp_cancel_adding_invoice 5498 5498 0 0.00 5 78038 866 0 0
sp_cancel_client_order 1887 1887 0 0.00 4 9781 357 0 0
sp_cancel_customer_reserve 535221 535221 0 0.00 1 65222 69 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 3 5750 79 0 0
sp_cancel_pay_to_supplier 453 453 0 0.00 2 4849 66 0 0
sp_cancel_supplier_invoice 6101 6091 10 0.16 2 28706 899 0 10
sp_cancel_supplier_order 426 426 0 0.00 6 1045898 109474 0 0
sp_cancel_write_off 9902 9902 0 0.00 1 14987 122 0 0
sp_client_order 21532 21532 0 0.00 3 29572 375 0 0
sp_customer_reserve 470518 470518 0 0.00 0 55172 183 0 0
sp_fill_shopping_cart 33182 33182 0 0.00 1 22167 41 0 0
sp_fill_shopping_cart_clo_res 470512 470512 0 0.00 0 12797 15 0 0
sp_get_clo_for_invoice 5194 5194 0 0.00 0 33774 318 0 0
sp_kill_cost_storno 16557 16557 0 0.00 0 14104 62 0 0
sp_kill_qstorno_ret_qs2qd 1086861 1086861 0 0.00 0 65213 37 0 0
sp_kill_qty_storno 574912 574912 0 0.00 0 65220 80 0 0
sp_lock_selected_doc 21950 21950 0 0.00 0 12634 8 0 0
sp_make_cost_storno 29485 29485 0 0.00 0 15514 58 0 0
sp_multiply_rows_for_pdistr 29485 29485 0 0.00 0 8899 29 0 0
sp_multiply_rows_for_qdistr 21532 21532 0 0.00 0 12193 158 0 0
sp_pay_from_customer 7429 7429 0 0.00 4 16490 125 0 0
sp_pay_to_supplier 6169 6169 0 0.00 5 21829 127 0 0
sp_payment_common 13598 13598 0 0.00 1 13270 35 0 0
sp_qd_handle_on_cancel_clo 1887 1887 0 0.00 0 9770 323 0 0
sp_qd_handle_on_invoice_upd_sts 10692 10692 0 0.00 0 18363 348 0 0
sp_qd_handle_on_reserve_upd_sts 10693 10693 0 0.00 0 11825 54 0 0
sp_reserve_write_off 10693 10693 0 0.00 6 23200 189 0 0
sp_supplier_invoice 5813 5813 0 0.00 7 84890 3415 0 0
sp_supplier_order 5839 5839 0 0.00 6 33488 1240 0 0
srv_aggregate_perf_data 103 103 0 0.00 2843 142347 16332 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1185 159771 42418 0 0
srv_find_qd_qs_mism 1045982 1045982 0 0.00 0 22213 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 22 119524 934 0 0
srv_make_money_saldo 806 806 0 0.00 0 12915 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 80384 364102 222045 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5200 5200 0 0.00 0 5934 147 0 0
t$perf-norm:sp_cancel_adding_invoice 423 423 0 0.00 0 2293 85 0 0
t$perf-norm:sp_cancel_client_order 1887 1887 0 0.00 0 880 8 0 0
t$perf-norm:sp_cancel_customer_reserve 711 711 0 0.00 0 1516 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 5983 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 453 453 0 0.00 0 892 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 3051 15 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 26550 1739 0 0
t$perf-norm:sp_cancel_write_off 1050 1050 0 0.00 0 3368 11 0 0
t$perf-norm:sp_client_order 21532 21532 0 0.00 0 7866 17 0 0
t$perf-norm:sp_customer_reserve 1095 1095 0 0.00 0 7282 18 0 0
t$perf-norm:sp_pay_from_customer 7429 7429 0 0.00 0 11609 13 0 0
t$perf-norm:sp_pay_to_supplier 6169 6169 0 0.00 0 5087 8 0 0
t$perf-norm:sp_reserve_write_off 10693 10693 0 0.00 0 8185 10 0 0
t$perf-norm:sp_supplier_invoice 5813 5813 0 0.00 0 7394 21 0 0
t$perf-norm:sp_supplier_order 5839 5839 0 0.00 0 7556 20 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 265 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 396 7 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 1434 22 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 6365 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10618 10388 230 2.16 0 5414 23 0 230
v_all_customers 39238 39238 0 0.00 0 16 1 0 0
v_all_suppliers 32368 32368 0 0.00 0 20 1 0 0
v_all_wares 43064 43064 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 846 846 0 0.00 0 4674 14 0 0
v_cancel_client_order 3774 3774 0 0.00 0 5999 7 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 5741 11 0 0
v_cancel_customer_reserve 1422 1422 0 0.00 0 4712 8 0 0
v_cancel_payment_to_supplier 906 906 0 0.00 0 4837 11 0 0
v_cancel_supplier_invoice 840 820 20 2.38 0 4297 24 0 20
v_cancel_supplier_order 852 852 0 0.00 0 2611 9 0 0
v_cancel_write_off 2100 2100 0 0.00 0 4037 19 0 0
v_max_id_clo_ord 5839 5839 0 0.00 0 2829 2 0 0
v_max_id_clo_res 1095 1095 0 0.00 0 1919 10 0 0
v_max_id_ord_sup 5811 5811 0 0.00 0 5733 9 0 0
v_max_non_paid_invoice 1635 1635 0 0.00 0 5374 8 0 0
v_max_non_paid_realizn 3955 3955 0 0.00 0 3428 5 0 0
v_min_id_clo_ord 5839 5839 0 0.00 0 5120 7 0 0
v_min_id_clo_res 1095 1095 0 0.00 0 7481 17 0 0
v_min_id_ord_sup 5811 5811 0 0.00 0 6914 13 0 0
v_min_non_paid_invoice 6169 6169 0 0.00 0 8562 21 0 0
v_min_non_paid_realizn 7429 7429 0 0.00 0 7437 26 0 0
v_our_firm 10774 10774 0 0.00 0 23 1 0 0
v_reserve_write_off 21384 21384 0 0.00 0 5685 8 0 0
x_lock_depdocs_on_canc_invoice 11179 11179 0 0.00 0 18692 122 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 8173 348 0 0
x_make_qstorno_1000_1200 5839 5839 0 0.00 2 33480 1160 0 0
x_make_qstorno_1200_2000 5813 5813 0 0.00 2 84880 3266 0 0
x_make_qstorno_2100_3300 470518 470518 0 0.00 0 55170 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-08-2021 16:40:06 to 30-08-2021 16:40:10.

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:51 6 8192000000 8809798944 8823345152 14528080 0.177 3 2 3 0 14759488 72208 159200
14:08:52 39443 8192000000 10375340816 10696851456 1422836320 14.798 100 87 174 0 1457385136 6201904 28346912
14:10:42 23734 8192000000 10427788544 10756325376 1481793248 15.317 100 91 182 0 1520006288 8523248 29689792
14:12:18 17940 8192000000 10486167136 10834583552 1541808880 15.839 100 82 164 1 1578054544 10227520 26018144
14:13:40 11304 8192000000 10592132768 10957590528 1652330880 16.784 100 82 164 1 1688782272 11534704 24916688
14:14:53 6540 8192000000 10655810480 11031412736 1716688816 17.325 100 72 144 0 1750723488 13296512 20738160
14:16:03 5056 8192000000 10726885504 11107065856 1789886288 17.931 100 77 154 1 1824496352 12910704 21699360
14:17:13 2697 8192000000 10777678464 11162423296 1832520160 18.280 100 89 178 2 1871913312 14178592 25214560
14:18:18 2735 8192000000 10790955888 11181826048 1858570192 18.492 100 93 189 2 1898926032 13735456 26620384
14:19:22 21054 8192000000 10816823008 11208200192 1869366208 18.579 100 82 163 3 1900751968 8996448 22389312
14:20:44 4927 8192000000 10807110064 11219005440 1883350928 18.692 100 48 95 2 1905222880 7812448 14059504
14:21:52 44256 8192000000 10814060784 11234283520 1894233632 18.780 100 33 64 2 1909455856 5030096 10192128
14:23:36 8195 8192000000 10817771792 11246727168 1906617712 18.879 100 28 56 1 1921113376 6977488 7518176
14:24:45 5783 8192000000 10819618736 11250860032 1905996640 18.874 100 19 38 4 1916841744 5478400 5366704
14:25:52 11601 8192000000 10836311216 11257851904 1913731504 18.937 100 35 69 3 1931021536 6530848 10759184
14:27:05 17634 8192000000 10813295456 11249405952 1873326240 18.611 98 21 42 1 1885099152 6329280 5443632
14:28:25 8959 8192000000 10776497312 11203522560 1836602544 18.313 97 23 46 0 1849139248 5576304 6960400
14:29:37 6194 8192000000 10705451248 11116670976 1777405984 17.828 97 22 43 2 1789846960 6476976 5964000
14:30:43 49138 8192000000 10655711248 11050856448 1721228768 17.362 96 44 84 2 1739512096 4911936 13371392
14:32:33 6426 8192000000 10722918816 11142762496 1783312192 17.877 98 27 53 1 1796603728 5629488 7662048
14:33:41 8843 8192000000 10763422416 11190804480 1845135552 18.383 100 25 51 1 1862361264 10297936 6927776
14:34:55 47056 8192000000 10778921424 11205484544 1865309808 18.546 100 17 32 0 1875176848 5523888 4343152
14:36:55 7397 8192000000 10779221200 11212402688 1870887392 18.591 100 26 52 0 1884593648 6055088 7651168
14:38:07 12215 8192000000 10837578720 11254820864 1908178960 18.892 100 39 79 1 1925617840 5152240 12286640
14:39:27 12648 8192000000 10864251024 11283947520 1926737040 19.041 100 50 100 1 1956652704 15302688 14612976
14:40:56 30313 8192000000 10899625040 11318517760 1947913040 19.210 100 49 98 0 1982098272 19744432 14440800
14:42:42 23630 8192000000 10900032048 11335983104 1946175376 19.196 99 39 79 1 1982645632 24924080 11546176
14:44:10 29193 8192000000 10917350432 11357970432 1972696752 19.407 100 38 76 1 2013671120 29230528 11743840
14:45:46 10926 8192000000 10933863488 11376132096 1988159664 19.529 100 34 68 1 2024970448 25713552 11097232
14:47:05 10287 8192000000 10969633504 11401465856 2009283424 19.696 100 62 120 1 2057614656 29958352 18372880
14:48:19 14406 8192000000 10964867360 11381432320 1991592576 19.556 99 64 125 1 2041787344 31025408 19169360
14:49:48 3151 8192000000 10971562768 11403669504 2005695184 19.668 100 68 136 2 2062212768 35952304 20565280
14:50:54 1184 8192000000 10995770064 11413114880 1999195664 19.616 99 89 180 1 2067152368 40736512 27220192
14:51:59 2090 8192000000 11011851296 11426504704 2017329552 19.759 100 96 192 1 2092189360 46243536 28616272
14:53:07 5899 8192000000 10999222080 11427155968 2009776992 19.700 100 68 135 2 2075872672 45562512 20533168
14:54:19 4910 8192000000 10965432048 11391496192 1983939856 19.496 100 69 137 0 2052410224 46627088 21843280
14:55:29 2816 8192000000 11004011264 11423170560 2007857184 19.685 100 87 174 1 2085958032 50456048 27644800
14:56:42 1620 8192000000 11009414240 11422294016 1994677872 19.581 100 90 179 6 2082200272 58851184 28671216
14:57:50 4248 8192000000 10955682032 11371053056 1952382544 19.245 99 74 148 1 2027622432 50527664 24712224
14:59:00 4647 8192000000 10966261984 11401539584 1989791520 19.542 100 55 109 1 2061927968 53024512 19111936
15:00:09 9450 8192000000 11006058832 11430494208 2006933200 19.677 100 72 139 1 2089382144 59587632 22861312
15:01:22 17004 8192000000 10977543088 11415023616 1974741280 19.423 99 47 94 1 2050141696 59334784 16065632
15:02:41 14120 8192000000 10973886240 11426000896 1989691952 19.541 100 34 68 1 2065891264 64201056 11998256
15:03:56 27529 8192000000 10954684720 11396571136 1967796272 19.368 100 27 54 0 2038442592 61268064 9378256
15:05:43 16892 8192000000 10932108464 11390803968 1945579456 19.191 100 27 54 2 2020215792 64890944 9745392
15:07:02 73096 8192000000 10877570912 11327242240 1884635952 18.703 96 26 52 0 1919084512 26514192 7934368
15:09:28 8591 8192000000 10886689968 11320635392 1946590064 19.199 100 48 96 1 1966702464 6132864 13979536
15:10:49 84512 8192000000 10888488416 11351687168 1947368096 19.206 99 26 52 2 1968861376 13928432 7564848
15:13:15 41356 8192000000 10880191920 11336720384 1954832976 19.265 100 11 22 0 1966909664 9320336 2756352
15:15:03 70834 8192000000 10899790656 11364450304 1952806880 19.249 99 16 33 1 1970331040 13002944 4521216
15:17:18 134876 8192000000 10918719712 11386417152 1976315712 19.436 100 19 38 0 1999300880 17334096 5651072
15:21:25 159770 8192000000 10896112800 11367424000 1924826336 19.025 97 18 36 1 1939616992 10259712 4530944
15:25:12 33044 8192000000 10915051504 11385434112 1993555184 19.572 100 22 44 0 2010606512 10598304 6453024
15:26:46 51466 8192000000 10912304448 11386732544 1991051264 19.552 100 18 36 1 2009628160 13403648 5173248
15:28:46 70554 8192000000 10910547440 11373854720 1965937232 19.353 99 15 30 1 1976077248 6115008 4025008
15:30:58 64990 8192000000 10895530272 11372507136 1981283552 19.475 100 21 42 0 1993055312 6221152 5550608
15:33:06 78602 8192000000 10888956528 11361550336 1964904832 19.345 99 20 40 0 1978567808 7359920 6303056
15:35:27 19455 8192000000 10928982032 11387502592 1995441296 19.587 100 57 114 0 2024140112 12521648 16177168
15:37:02 67476 8192000000 10891851312 11383746560 1956172384 19.276 99 7 14 0 1973015520 15181184 1661952
15:39:10 61284 8192000000 10890340160 11361853440 1923340992 19.014 97 18 36 4 1938288864 9832464 5115408
15:41:32 61245 8192000000 10863044400 11332747264 1881050608 18.674 95 26 52 3 1898191552 10077664 7063280
15:43:38 18052 8192000000 10900751328 11353006080 1950677600 19.232 99 63 122 0 1981506048 12694576 18133872
15:45:01 42377 8192000000 10876262064 11325591552 1888675040 18.735 97 20 40 1 1901896272 7803472 5417760
15:46:43 29766 8192000000 10911204432 11336642560 1935545184 19.111 99 47 94 2 1959423552 11138000 12740368
15:48:15 6676 8192000000 10884390560 11328811008 1924424016 19.022 99 44 88 3 1949750560 13608384 11718160
15:49:22 54555 8192000000 10834922320 11298648064 1768589264 17.755 91 9 18 2 1782406992 11579872 2237856
15:51:21 132840 8192000000 10812085968 11268722688 1850364640 18.425 96 17 34 0 1865888368 10815280 4708448
15:54:35 31427 8192000000 10854538896 11325337600 1911312192 18.917 99 17 34 0 1927986928 11963168 4711568
15:56:18 57654 8192000000 10847864352 11329548288 1902004080 18.842 98 9 17 0 1915207024 11218672 1984272
15:58:53 82860 8192000000 10868663168 11356811264 1927316992 19.045 99 7 14 0 1938759152 9282560 2159600
16:01:16 42676 8192000000 10835582752 11306237952 1878814816 18.656 97 29 53 3 1893602832 7343104 7444912
16:02:59 29313 8192000000 10883631152 11342749696 1948335088 19.213 100 32 64 1 1970836480 13002640 9498752
16:04:32 83731 8192000000 10888067152 11357073408 1937373088 19.126 99 16 32 0 1956792656 14908560 4511008
16:06:59 28653 8192000000 10912291840 11365548032 1948127040 19.212 99 37 73 1 1965033216 7224816 9681360
16:08:36 77263 8192000000 10935073632 11405856768 1978209408 19.451 100 17 34 2 2006473520 24069968 4194144
16:11:07 34267 8192000000 10932295728 11396079616 1990022592 19.544 100 32 64 1 2008833344 9701472 9109280
16:12:43 81171 8192000000 10920504240 11400175616 1980431840 19.468 99 23 46 2 1998575920 11955280 6188800
16:15:04 73628 8192000000 10926713040 11374755840 1953703248 19.256 98 32 64 0 1970789584 7976144 9110192
16:17:18 50470 8192000000 10865631536 11342626816 1913240192 18.933 97 16 32 1 1925461744 7662832 4558720
16:19:16 40309 8192000000 10870985952 11346812928 1907176352 18.884 97 10 20 2 1916719872 6497568 3045952
16:21:01 60593 8192000000 10884872848 11372007424 1948735968 19.216 99 9 18 0 1963114496 11685248 2693280
16:23:02 34624 8192000000 10886288880 11348303872 1903596848 18.855 97 21 39 0 1915686720 6821664 5268208
16:24:38 33646 8192000000 10853432336 11318988800 1908644912 18.896 98 24 48 1 1923715840 8573536 6497392
16:26:11 27675 8192000000 10860316832 11329888256 1919396064 18.982 99 34 68 0 1939138224 10256848 9485312
16:27:56 22349 8192000000 10874503168 11337658368 1917727136 18.969 98 29 58 1 1934800144 7810864 9262144
16:29:28 46347 8192000000 10889620720 11351601152 1934900160 19.106 99 27 54 3 1954328240 12207792 7220288
16:31:18 69843 8192000000 10855902016 11315462144 1850666672 18.428 95 17 34 1 1865748784 10108528 4973584
16:33:28 33279 8192000000 10867308688 11331788800 1914860688 18.946 99 44 88 2 1939865824 12627792 12377344
16:35:05 133094 8192000000 10839056016 11307151360 1880989104 18.673 97 38 76 2 1906022384 14828080 10205200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-08-2021 16:40:12 to 30-08-2021 16:40:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 30-08-2021 16:40:28 to 30-08-2021 16:40:30.

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          247400
MON$OLDEST_ACTIVE               248442
MON$OLDEST_SNAPSHOT             248442
MON$NEXT_TRANSACTION            248442
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                       2030752
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:750: 
:751: mscs-test-161.budzdorov.ru    Mon Aug 30 16:38:29 2021
:752:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:753: 
:754: 
:755: mscs-test-161.budzdorov.ru    Mon Aug 30 16:38:31 2021
:756:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:757: 
--- end of diff output ---

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

30.08.21 16:40:31. 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-30 16:40:33 No severe PSQL-related problems occured
30.08.21 16:40:34. End of report.