Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 16:43
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 16154560 kB
MemAvailable 30859076 kB
Buffers 0 kB
Cached 15514796 kB
SwapCached 340 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-09-07 16:42:48
DTS_END                         2021-09-07 16:42:48.4050
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/56068
TRN_ID                          240081
ATT_ID                          697
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.93 20460 1127152
customer order: creation 171.92 364 20630
customer order: refuse 14.36 377 1723
order to supplier: creation 46.18 1231 5541
order to supplier: removal 3.58 99991 430
invoice (draft): creation 45.90 3579 5508
invoice (draft): removal 54.09 775 6491
invoice accept: apply 41.90 17570 5028
invoice accept: cancel 48.65 783 5838
customer reserve: creation 3859.15 174 463098
customer reserve: removal 4814.77 59 577773
realization accept: apply 82.17 168 9860
realization accept: cancel 82.33 114 9880
payment from customer: creation 58.91 128 7069
payment from customer: removal 5.88 106 705
payment to supplier: creation 47.68 133 5721
payment to supplier: removal 3.38 106 406
service: total inventory turnovers 4.66 1203 559
service: total monetary turnovers 6.73 59 807
service: aggregate perf. data 0.68 27044 81
service: refresh index statistics 0.03 255244 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 16:43:56 to 07-09-2021 16:43:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 668
TEST_TIME 2 1215
TEST_TIME 3 1949
TEST_TIME 4 2651
TEST_TIME 5 3320
TEST_TIME 6 3477
TEST_TIME 7 3674
TEST_TIME 8 4144
TEST_TIME 9 4349
TEST_TIME 10 4480
TEST_TIME 11 5021
TEST_TIME 12 5635
TEST_TIME 13 5796
TEST_TIME 14 5968
TEST_TIME 15 6461
TEST_TIME 16 6703
TEST_TIME 17 7033
TEST_TIME 18 7531
TEST_TIME 19 7893
TEST_TIME 20 7946
TEST_TIME 21 8034
TEST_TIME 22 8405
TEST_TIME 23 8948
TEST_TIME 24 9187
TEST_TIME 25 9431
TEST_TIME 26 9909
TEST_TIME 27 10364
TEST_TIME 28 10661
TEST_TIME 29 10795
TEST_TIME 30 10590
TEST_TIME 31 10418
TEST_TIME 32 10146
TEST_TIME 33 9978
TEST_TIME 34 10198
TEST_TIME 35 10074
TEST_TIME 36 9854
TEST_TIME 37 9811
TEST_TIME 38 9836
TEST_TIME 39 9805
TEST_TIME 40 9773
TEST_TIME 41 9847
TEST_TIME 42 9863
TEST_TIME 43 9829
TEST_TIME 44 9838
TEST_TIME 45 9694
TEST_TIME 46 9630
TEST_TIME 47 9589
TEST_TIME 48 9534
TEST_TIME 49 9455
TEST_TIME 50 9473
TEST_TIME 51 9592
TEST_TIME 52 9549
TEST_TIME 53 9583
TEST_TIME 54 9585
TEST_TIME 55 9645
TEST_TIME 56 9701
TEST_TIME 57 9665
TEST_TIME 58 9700
TEST_TIME 59 9743
TEST_TIME 60 9780
TEST_TIME 61 9789
TEST_TIME 62 9864
TEST_TIME 63 9758
TEST_TIME 64 9730
TEST_TIME 65 9664
TEST_TIME 66 9643
TEST_TIME 67 9582
TEST_TIME 68 9503
TEST_TIME 69 9472
TEST_TIME 70 9388
TEST_TIME 71 9352
TEST_TIME 72 9468
TEST_TIME 73 9445
TEST_TIME 74 9413
TEST_TIME 75 9405
TEST_TIME 76 9352
TEST_TIME 77 9573
TEST_TIME 78 9542
TEST_TIME 79 9546
TEST_TIME 80 9633
TEST_TIME 81 9617
TEST_TIME 82 9636
TEST_TIME 83 9708
TEST_TIME 84 9664
TEST_TIME 85 9568
TEST_TIME 86 9502
TEST_TIME 87 9503
TEST_TIME 88 9435
TEST_TIME 89 9411
TEST_TIME 90 9360
TEST_TIME 91 9338
TEST_TIME 92 9355
TEST_TIME 93 9334
TEST_TIME 94 9371
TEST_TIME 95 9388
TEST_TIME 96 9364
TEST_TIME 97 9417
TEST_TIME 98 9435
TEST_TIME 99 9495
TEST_TIME 100 9485
TEST_TIME 101 9477
TEST_TIME 102 9502
TEST_TIME 103 9514
TEST_TIME 104 9539
TEST_TIME 105 9551
TEST_TIME 106 9538
TEST_TIME 107 9456
TEST_TIME 108 9482
TEST_TIME 109 9435
TEST_TIME 110 9422
TEST_TIME 111 9392
TEST_TIME 112 9335
TEST_TIME 113 9369
TEST_TIME 114 9369
TEST_TIME 115 9364
TEST_TIME 116 9351
TEST_TIME 117 9322
TEST_TIME 118 9347
TEST_TIME 119 9353
TEST_TIME 120 9400

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-09-2021 16:43:59 to 07-09-2021 16:44:03.

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 1557254 1557254 0 0.00 0 13193 6 0 0
doc_list_biud 1557248 1557248 0 0.00 0 49924 27 0 0
sp_add_invoice_to_stock 5130 5028 102 1.98 32 187581 17571 0 102
sp_cancel_adding_invoice 5838 5838 0 0.00 5 51011 784 0 0
sp_cancel_client_order 1723 1723 0 0.00 4 10837 378 0 0
sp_cancel_customer_reserve 577773 577773 0 0.00 1 49926 59 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 3 7441 107 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 6571 106 0 0
sp_cancel_supplier_invoice 6501 6491 10 0.15 2 32808 776 0 10
sp_cancel_supplier_order 430 430 0 0.00 6 1045881 99991 0 0
sp_cancel_write_off 9880 9880 0 0.00 1 14884 114 0 0
sp_client_order 20630 20630 0 0.00 4 47451 364 0 0
sp_customer_reserve 463098 463098 0 0.00 0 111284 175 0 0
sp_fill_shopping_cart 31667 31667 0 0.00 1 17394 40 0 0
sp_fill_shopping_cart_clo_res 463091 463091 0 0.00 0 32444 14 0 0
sp_get_clo_for_invoice 5021 5021 0 0.00 0 34162 292 0 0
sp_kill_cost_storno 16829 16829 0 0.00 0 11707 58 0 0
sp_kill_qstorno_ret_qs2qd 1172347 1172347 0 0.00 0 49922 32 0 0
sp_kill_qty_storno 617016 617016 0 0.00 0 49924 69 0 0
sp_lock_selected_doc 20722 20722 0 0.00 0 14382 5 0 0
sp_make_cost_storno 27671 27671 0 0.00 0 10728 48 0 0
sp_multiply_rows_for_pdistr 27671 27671 0 0.00 0 6378 30 0 0
sp_multiply_rows_for_qdistr 20630 20630 0 0.00 0 47445 180 0 0
sp_pay_from_customer 7069 7069 0 0.00 4 21914 129 0 0
sp_pay_to_supplier 5721 5721 0 0.00 5 14707 134 0 0
sp_payment_common 12790 12790 0 0.00 1 8497 33 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 10828 341 0 0
sp_qd_handle_on_invoice_upd_sts 10859 10859 0 0.00 0 30439 320 0 0
sp_qd_handle_on_reserve_upd_sts 9860 9860 0 0.00 0 14265 48 0 0
sp_reserve_write_off 9860 9860 0 0.00 6 15140 169 0 0
sp_supplier_invoice 5508 5508 0 0.00 7 126508 3579 0 0
sp_supplier_order 5541 5541 0 0.00 5 23305 1231 0 0
srv_aggregate_perf_data 81 81 0 0.00 2356 315468 27044 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2205 131865 44882 0 0
srv_find_qd_qs_mism 1080190 1080190 0 0.00 0 13190 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 27 60083 1204 0 0
srv_make_money_saldo 807 807 0 0.00 0 19895 60 0 0
srv_recalc_idx_stat 4 4 0 0.00 169546 356443 255245 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 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 5029 5029 0 0.00 0 16899 174 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 7515 102 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 2291 10 0 0
t$perf-norm:sp_cancel_customer_reserve 748 748 0 0.00 0 839 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 308 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 115 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 2650 14 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 25094 1510 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 501 4 0 0
t$perf-norm:sp_client_order 20630 20630 0 0.00 0 7916 18 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 1427 6 0 0
t$perf-norm:sp_pay_from_customer 7069 7069 0 0.00 0 3852 9 0 0
t$perf-norm:sp_pay_to_supplier 5721 5721 0 0.00 0 4051 10 0 0
t$perf-norm:sp_reserve_write_off 9860 9860 0 0.00 0 7567 12 0 0
t$perf-norm:sp_supplier_invoice 5508 5508 0 0.00 0 5256 18 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 5833 16 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 561 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 841 14 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1008 20 0 0
t$perf-norm:srv_make_money_saldo 807 807 0 0.00 0 711 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10246 10042 204 1.99 0 15019 27 0 204
v_all_customers 37626 37626 0 0.00 0 20 1 0 0
v_all_suppliers 30382 30382 0 0.00 0 16 1 0 0
v_all_wares 41260 41260 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 3030 12 0 0
v_cancel_client_order 3446 3446 0 0.00 0 4617 11 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 3547 16 0 0
v_cancel_customer_reserve 1496 1496 0 0.00 0 11022 25 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 6503 21 0 0
v_cancel_supplier_invoice 798 778 20 2.50 0 2903 25 0 20
v_cancel_supplier_order 860 860 0 0.00 0 3873 7 0 0
v_cancel_write_off 2040 2040 0 0.00 0 1382 12 0 0
v_max_id_clo_ord 5539 5539 0 0.00 0 327 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 2679 18 0 0
v_max_id_ord_sup 5498 5498 0 0.00 0 10108 7 0 0
v_max_non_paid_invoice 1567 1567 0 0.00 0 6369 9 0 0
v_max_non_paid_realizn 3714 3714 0 0.00 0 1555 3 0 0
v_min_id_clo_ord 5539 5539 0 0.00 0 1621 5 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 4497 9 0 0
v_min_id_ord_sup 5498 5498 0 0.00 0 8669 12 0 0
v_min_non_paid_invoice 5721 5721 0 0.00 0 8492 19 0 0
v_min_non_paid_realizn 7069 7069 0 0.00 0 8227 24 0 0
v_our_firm 10344 10344 0 0.00 0 12 1 0 0
v_reserve_write_off 19720 19720 0 0.00 0 12707 11 0 0
x_lock_depdocs_on_canc_invoice 11940 11940 0 0.00 0 24863 116 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 3015 280 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 1 23292 1153 0 0
x_make_qstorno_1200_2000 5508 5508 0 0.00 3 126493 3433 0 0
x_make_qstorno_2100_3300 463098 463098 0 0.00 0 111283 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 16:44:04 to 07-09-2021 16:44:08.

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:12:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:13:53 25275 8192000000 10414428096 10739675136 1461175328 15.136 100 89 178 0 1500566320 10971584 28419408
14:15:25 18632 8192000000 10463784448 10802503680 1521117440 15.660 100 85 170 0 1557349680 8977312 27254928
14:16:48 10768 8192000000 10517444768 10861539328 1569648512 16.079 100 83 166 0 1605882176 10050224 26183440
14:18:05 10751 8192000000 10556145568 10912665600 1618619328 16.498 100 78 156 2 1653535568 11687824 23228416
14:19:22 7545 8192000000 10614531088 10978402304 1672246928 16.952 100 83 165 0 1708869280 12002240 24620112
14:20:35 4416 8192000000 10690816816 11067609088 1753989680 17.635 100 80 161 1 1788580496 12395008 22195808
14:21:41 2960 8192000000 10742448000 11123183616 1801259600 18.024 100 85 170 0 1839912064 13509280 25143184
14:22:45 2234 8192000000 10798192416 11181023232 1849153376 18.415 100 91 184 1 1888404400 14079664 25171360
14:23:48 1302 8192000000 10827343232 11220787200 1891252048 18.756 100 90 180 0 1929337808 12583616 25502144
14:24:50 14441 8192000000 10789226032 11221110784 1883955872 18.697 100 38 76 0 1904520192 9852928 10711392
14:26:06 7503 8192000000 10802100736 11230277632 1894218528 18.780 100 24 48 1 1907379440 5934240 7226672
14:27:14 3801 8192000000 10827732192 11249913856 1907986464 18.890 100 32 65 0 1924581824 7552352 9043008
14:28:22 22267 8192000000 10821464512 11260952576 1915982320 18.955 100 19 39 2 1927450560 5312704 6155536
14:29:44 5589 8192000000 10832096720 11267850240 1919184432 18.980 100 31 62 0 1935075936 7389664 8501840
14:30:53 7833 8192000000 10845809104 11279765504 1927682928 19.048 100 33 66 0 1943494432 6091648 9719856
14:32:01 8060 8192000000 10827127952 11264413696 1912055936 18.923 100 15 30 1 1922255680 5570992 4628752
14:33:13 57340 8192000000 10772874688 11211362304 1822869600 18.201 96 34 68 0 1838895104 5502624 10522880
14:35:15 4001 8192000000 10751093760 11169714176 1824746720 18.216 100 32 63 0 1841331536 7473600 9111216
14:36:20 5261 8192000000 10696464992 11103875072 1776809712 17.823 99 39 78 1 1794545600 5777440 11958448
14:37:26 22707 8192000000 10722980528 11149832192 1764427328 17.721 97 27 54 0 1777934288 5454224 8052736
14:38:48 12327 8192000000 10726514464 11149381632 1801124496 18.023 99 16 32 0 1811113952 5552944 4436512
14:40:05 11133 8192000000 10767687888 11194593280 1853664560 18.452 100 21 42 1 1865600048 5881184 6054304
14:41:17 7646 8192000000 10786857680 11211898880 1867085552 18.561 100 28 54 3 1880234656 4990400 8158704
14:42:25 95430 8192000000 10830661184 11250667520 1899602816 18.823 100 35 70 0 1915871504 5606464 10662224
14:45:13 29909 8192000000 10864024880 11281891328 1930901840 19.074 100 50 98 1 1956971456 12158992 13910624
14:46:47 17083 8192000000 10880595712 11306917888 1947549568 19.207 100 51 101 1 1975349168 13541456 14258144
14:48:08 15415 8192000000 10896207968 11331899392 1968996912 19.377 100 48 96 2 1999665232 15892544 14775776
14:49:30 9001 8192000000 10932255696 11360796672 1969356848 19.380 99 48 96 1 2007641408 24121504 14163056
14:50:47 19344 8192000000 10934999840 11371315200 1985602656 19.509 100 42 85 0 2025541968 27312704 12626608
14:52:11 3337 8192000000 10970280528 11395698688 2008567088 19.690 100 65 128 3 2059406912 31802000 19037824
14:53:20 10018 8192000000 10979929216 11415818240 2018450976 19.768 100 67 134 3 2076079984 35653728 21975280
14:54:34 6669 8192000000 10997085888 11426603008 2019292464 19.775 100 70 140 0 2077671168 37480112 20898592
14:55:49 7070 8192000000 10982786608 11408945152 2000848320 19.629 99 70 140 0 2059105536 36998304 21258912
14:57:01 7921 8192000000 10981328848 11402477568 1997312816 19.602 100 77 154 2 2061848960 41537744 22998400
14:58:16 4082 8192000000 10925412560 11339522048 1920715952 18.993 98 86 170 1 1986601920 40470720 25415248
14:59:29 2200 8192000000 10963473808 11381399552 1974042720 19.418 100 74 147 0 2042256384 46075632 22138032
15:00:35 4702 8192000000 10928366400 11359760384 1955077824 19.267 100 64 128 0 2018338400 42799248 20461328
15:01:51 5350 8192000000 10937692416 11386527744 1972438144 19.405 100 31 62 2 2027984368 44870144 10676080
15:03:00 6229 8192000000 10941108320 11365298176 1945767056 19.193 99 62 124 0 2005595584 40170848 19657680
15:04:09 17687 8192000000 10931279024 11367837696 1953057744 19.251 100 40 80 0 2014486560 47710704 13718112
15:05:29 19542 8192000000 10905172400 11355336704 1933771472 19.097 99 30 60 1 1989615856 46009952 9834432
15:06:51 12259 8192000000 10900892656 11342086144 1924097328 19.020 99 40 79 0 1978314400 41205056 13012016
15:08:20 30299 8192000000 10877164240 11329744896 1895471056 18.790 98 24 48 1 1935137168 31245712 8420400
15:09:51 22644 8192000000 10879469104 11329900544 1927168384 19.044 100 11 22 1 1956113424 25248048 3696992
15:11:13 81787 8192000000 10867510656 11327815680 1877386992 18.644 97 5 10 0 1896474032 18037296 1049744
15:13:39 104270 8192000000 10850146624 11286966272 1866405072 18.555 96 38 76 2 1886904320 9429040 11070208
15:16:33 39412 8192000000 10881615216 11332390912 1945393808 19.190 100 19 38 2 1961183568 11359776 4429984
15:18:16 131865 8192000000 10875385904 11331166208 1810716528 18.102 92 26 52 0 1835098672 16467376 7914768
15:21:55 47161 8192000000 10911390448 11364077568 1961376976 19.317 100 45 90 0 1998899296 24292592 13229728
15:23:45 50991 8192000000 10888071696 11374264320 1942662000 19.168 99 8 16 0 1974762240 29278560 2821680
15:25:40 44391 8192000000 10902190592 11368943616 1968558192 19.374 100 17 35 0 1987536880 14209904 4768784
15:27:25 66958 8192000000 10886939904 11362889728 1966492080 19.358 100 12 23 1 1981533440 11263744 3777616
15:30:05 38991 8192000000 10887112448 11370713088 1965986656 19.354 100 4 8 0 1984024832 16723072 1315104
15:31:44 55643 8192000000 10905526048 11381936128 1950272976 19.229 99 8 16 1 1967180992 14690656 2217360
15:33:40 76812 8192000000 10924026368 11380080640 1916534256 18.959 96 20 39 2 1934588944 12737808 5316880
15:36:02 38188 8192000000 10921103552 11387523072 1986078032 19.513 100 25 48 0 2005904864 12170464 7656368
15:37:45 33187 8192000000 10917390000 11386937344 1976668112 19.438 99 23 46 0 1994426048 11135792 6622144
15:39:24 29690 8192000000 10930677088 11375714304 1964330848 19.340 99 38 77 1 1988239968 13136112 10773008
15:41:08 64139 8192000000 10890951296 11369304064 1901694512 18.840 96 17 34 1 1918655888 12663536 4297840
15:44:42 105019 8192000000 10850433184 11317440512 1851468208 18.434 94 26 52 1 1868894288 10568960 6857120
15:48:33 72392 8192000000 10843636096 11319840768 1916309040 18.957 99 13 26 1 1930679648 10765152 3605456
15:50:45 73030 8192000000 10835309984 11304353792 1838578624 18.329 95 13 26 0 1855255392 12739904 3936864
15:53:11 52535 8192000000 10841366944 11312156672 1893961376 18.778 99 18 36 1 1919882320 20026992 5893952
15:55:17 30081 8192000000 10867013104 11328167936 1926993072 19.043 99 13 26 0 1937238096 6939792 3305232
15:56:49 53479 8192000000 10862222192 11330617344 1886026976 18.714 97 14 28 0 1907088288 17226176 3835136
15:58:47 81577 8192000000 10851463472 11333627904 1891003664 18.754 97 17 34 2 1911786368 15885200 4897504
16:01:34 75622 8192000000 10861704304 11335368704 1880831808 18.672 97 14 27 2 1900636864 15721648 4083408
16:03:49 42307 8192000000 10893854752 11348791296 1940373696 19.150 100 32 64 1 1962992624 14080736 8538192
16:05:32 77917 8192000000 10892952928 11351818240 1899650400 18.823 97 20 40 1 1922320512 17271472 5398640
16:07:51 55450 8192000000 10861597040 11306172416 1888166880 18.731 96 41 83 0 1907408288 7570160 11671248
16:09:50 45075 8192000000 10865371088 11348234240 1949783680 19.225 100 4 8 0 1960658864 9655136 1220048
16:11:35 56265 8192000000 10876951520 11349393408 1908317584 18.893 97 8 16 0 1920110752 9565312 2227856
16:13:43 76387 8192000000 10898362256 11380928512 1958564640 19.295 99 13 26 0 1971633168 9415024 3653504
16:16:01 51279 8192000000 10912576256 11380690944 1957279760 19.284 98 26 52 0 1971657888 6791472 7586656
16:17:52 49756 8192000000 10947058928 11399458816 1979424464 19.460 99 27 54 0 1997742624 10850640 7467520
16:19:47 71335 8192000000 10909885360 11390103552 1965427072 19.349 99 14 29 0 1988171328 18871920 3872336
16:21:59 20877 8192000000 10911264272 11380891648 1984861088 19.503 100 7 14 1 1994570864 8255696 1454080
16:23:21 19332 8192000000 10946508656 11392303104 1978837536 19.455 99 57 114 1 2004272048 9491888 15942624
16:24:41 43775 8192000000 10940148144 11412434944 1984005536 19.496 99 24 48 1 2007895904 17208672 6681696
16:26:25 71243 8192000000 10924510720 11390521344 1940602032 19.152 97 26 52 1 1961679536 13656528 7420976
16:28:40 101182 8192000000 10896739040 11373309952 1947597072 19.207 98 16 32 0 1969108800 16417296 5094432
16:31:29 7483 8192000000 10924513632 11380830208 1980214160 19.466 100 45 92 2 2004049232 11063088 12771984
16:32:37 61669 8192000000 10879269888 11358142464 1894437184 18.782 96 16 33 0 1910913344 11954272 4521888
16:34:39 24403 8192000000 10871454384 11351760896 1946687488 19.200 100 10 20 1 1969587408 19822032 3077888
16:36:04 41698 8192000000 10865025520 11334008832 1911293360 18.917 98 24 49 0 1928105712 9898480 6913872
16:37:48 89894 8192000000 10876113152 11349536768 1859907824 18.503 95 14 28 1 1873787392 9479168 4400400
16:40:26 71619 8192000000 10913077680 11374465024 1955677456 19.272 100 21 43 1 1983384032 21210880 6495696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-09-2021 16:44:10 to 07-09-2021 16:44:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 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 07-09-2021 16:44:26 to 07-09-2021 16:44:27.

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          236963
MON$OLDEST_ACTIVE               240196
MON$OLDEST_SNAPSHOT             240196
MON$NEXT_TRANSACTION            240196
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                       2027936
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.33492 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33492 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33492 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33492 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    Tue Sep  7 16:42:59 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Sep  7 16:42:59 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
--- end of diff output ---

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

07.09.21 16:44:28. 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-09-07 16:44:31 No severe PSQL-related problems occured
07.09.21 16:44:32. End of report.